Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525220530\orcl_ora_14424_bucket.trc Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options Windows NT Version V6.2 CPU : 32 - type 8664, 16 Physical Cores Process Affinity : 0x0x0000000000000000 Memory (Avail/Total): Ph:37328M/63366M, Ph+PgF:44359M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14424, image: ORACLE.EXE (SHAD) *** 2025-05-25 22:05:30.919 *** 2025-05-25 22:05:30.919 Process diagnostic dump for ORACLE.EXE (SHAD), OS id=14424, pid: 29, proc_ser: 218, sid: ------------------------------------------------------------------------------- Skipping session state dump because target process has no session. ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 22:05:30.919 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14424) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 20:45:15.023678 :9BF3F09D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13828 osp_idx=27 osp_ver=11687 osp_pg=0 (spawn #218988) 2025-05-25 20:45:15.023678 :9BF3F09E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 20:45:15.023679 :9BF3F09F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=69 time=1181687266 2025-05-25 20:45:15.023679 :9BF3F0A0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 20:45:15.023680 :9BF3F0A1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 20:45:15.023680 :9BF3F0A2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 20:45:15.023680 :9BF3F0A3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 20:45:15.023680 :9BF3F0A4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 20:45:15.024258 :9BF3F0A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 20:45:15.024301 :9BF3F0AB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 20:45:15.028372 :9BF3F0D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 20:45:15.028434 :9BF3F0D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 20:45:15.028763 :9BF3F0D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 20:45:15.028781 :9BF3F0D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 20:45:15.028998 :9BF3F0D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 20:45:15.029014 :9BF3F0D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 20:45:15.029225 :9BF3F0DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 20:45:15.029238 :9BF3F0DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 20:45:15.029459 :9BF3F0DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 20:45:15.029472 :9BF3F0DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 20:45:15.029671 :9BF3F0DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 20:45:15.029684 :9BF3F0DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 20:45:15.031986 :9BF3F0E0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 21:15:19.535794 :9BF64CE6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 21:15:19.523 2025-05-25 21:15:19.535796 :9BF64CE7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9764 pso_num=29 pso_serial#=214 2025-05-25 21:15:19.535929 :9BF64CE8:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9764 osp_idx=27 osp_ver=11688 osp_pg=0 (spawn #219058) 2025-05-25 21:15:19.535929 :9BF64CE9:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 21:15:19.535930 :9BF64CEA:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=70 time=1183491782 2025-05-25 21:15:19.535930 :9BF64CEB:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 21:15:19.535930 :9BF64CEC:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 21:15:19.535931 :9BF64CED:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 21:15:19.535931 :9BF64CEE:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 21:15:19.535931 :9BF64CEF:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 21:15:19.536436 :9BF64CF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 21:15:19.536477 :9BF64CF6:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 21:15:19.540606 :9BF64D1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:15:19.540645 :9BF64D20:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:15:19.540944 :9BF64D21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:15:19.540971 :9BF64D22:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:15:19.541219 :9BF64D23:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:15:19.541232 :9BF64D24:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:15:19.541442 :9BF64D25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:15:19.541457 :9BF64D26:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:15:19.541671 :9BF64D27:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:15:19.541684 :9BF64D28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:15:19.541902 :9BF64D29:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:15:19.541915 :9BF64D2A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:15:19.544208 :9BF64D2B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 21:45:23.858499 :9BF895F4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 21:45:23.846 2025-05-25 21:45:23.858500 :9BF895F5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15184 pso_num=29 pso_serial#=215 2025-05-25 21:45:23.858629 :9BF895F6:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15184 osp_idx=27 osp_ver=11689 osp_pg=0 (spawn #219126) 2025-05-25 21:45:23.858630 :9BF895F7:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 21:45:23.858630 :9BF895F8:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=71 time=1185296110 2025-05-25 21:45:23.858630 :9BF895F9:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 21:45:23.858631 :9BF895FA:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 21:45:23.858631 :9BF895FB:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 21:45:23.858632 :9BF895FC:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 21:45:23.858632 :9BF895FD:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 21:45:23.859119 :9BF895FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 21:45:23.859159 :9BF89604:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 21:45:23.863327 :9BF8961D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:45:23.863368 :9BF8961E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:45:23.863639 :9BF8961F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:45:23.863665 :9BF89620:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:45:23.863936 :9BF89621:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:45:23.863950 :9BF89622:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:45:23.864163 :9BF89623:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:45:23.864176 :9BF89624:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:45:23.864392 :9BF89625:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:45:23.864405 :9BF89626:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:45:23.864611 :9BF89627:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-25 21:45:23.864624 :9BF89628:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-25 21:45:23.866939 :9BF89629:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 22:00:25.846426 :9BF9BB59:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 22:00:25.834 2025-05-25 22:00:25.846428 :9BF9BB5A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13024 pso_num=29 pso_serial#=216 2025-05-25 22:00:25.846569 :9BF9BB5B:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13024 osp_idx=27 osp_ver=11690 osp_pg=0 (spawn #219158) 2025-05-25 22:00:25.846569 :9BF9BB5C:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 22:00:25.846569 :9BF9BB5D:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=72 time=1186198094 2025-05-25 22:00:25.846570 :9BF9BB5E:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 22:00:25.846570 :9BF9BB5F:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 22:00:25.846571 :9BF9BB60:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 22:00:25.846571 :9BF9BB61:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 22:00:25.846572 :9BF9BB62:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 22:00:25.847081 :9BF9BB63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.847122 :9BF9BB69:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 22:00:25.851575 :9BF9BB7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.879649 :9BF9BBAE:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286508713 8458584) 2025-05-25 22:00:25.879660 :9BF9BBAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.879720 :9BF9BBB9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 22:00:25.879767 :9BF9BBC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.894864 :9BF9BBCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.921362 :9BF9BBD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.925039 :9BF9BBDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.928792 :9BF9BBE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.933025 :9BF9BBEF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.934101 :9BF9BBF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.955314 :9BF9BBFF:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 3, ext 1, nex 2 2025-05-25 22:00:25.955322 :9BF9BC00:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081112e where: kdiwh22: kdifind 2025-05-25 22:00:25.963211 :9BF9BC01:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 3, ext 2, nex 3 2025-05-25 22:00:25.963219 :9BF9BC02:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008106fe where: kdiwh22: kdifind 2025-05-25 22:00:25.981374 :9BF9BC03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:25.981387 :9BF9BC04:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0003.008.0000c18e 2025-05-25 22:00:25.981607 :9BF9BC07:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 3, ext 3, nex 4 2025-05-25 22:00:25.981614 :9BF9BC08:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081140b where: ktspbwh1: ktspfsrch 2025-05-25 22:00:26.563818 :9BF9BC1A:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 3, ext 4, nex 5 2025-05-25 22:00:26.563825 :9BF9BC1B:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00810992 where: ktswh76: ktsxadd 2025-05-25 22:00:26.990303*:9BF9BC46:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029999D30 estart=1748181625 eid=16778747 ctx=0x000000077BA4B1C8 cwh=0x0000000029950AF8 cpu=437500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-25 22:00:26.990303*:9BF9BC47:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029999D30 curCtx=0x000000002B5B8EC0 pubTabIdxCnt=0 2025-05-25 22:00:26.990303*:9BF9BC48:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029999D30 2025-05-25 22:00:26.990303*:9BF9BC49:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029999D30 2025-05-25 22:00:26.990303*:9BF9BC4A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029993980 estart=1748181625 eid=16778738 ctx=0x0000000787AD5BF0 cwh=0x0000000029950918 cpu=437500 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-25 22:00:26.990303*:9BF9BC4B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029993980 curCtx=0x000000002B5BADC8 pubTabIdxCnt=0 2025-05-25 22:00:26.990303*:9BF9BC4C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029993980 2025-05-25 22:00:26.990303*:9BF9BC4D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029993980 2025-05-25 22:00:27.115329*:9BF9BC5A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029999D30 curCtx=0x000000002B5B8EC0 xsc->flg4=65792 flags=1 2025-05-25 22:00:27.115329*:9BF9BC5B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029999D30 2025-05-25 22:00:27.115329*:9BF9BC5C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029993980 curCtx=0x000000002B5BADC8 xsc->flg4=65792 flags=2 2025-05-25 22:00:27.115329*:9BF9BC5D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029993980 2025-05-25 22:00:27.188225 :9BF9BC5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:27.216408 :9BF9BC66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:27.216744 :9BF9BC6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:27.217022 :9BF9BC76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:27.217038 :9BF9BC79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:00:27.217109 :9BF9BC89:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-25 22:00:27.220458 :9BF9BC93:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 22:05:26.498353 :9BFA1FA5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 22:05:26.486 2025-05-25 22:05:26.498354 :9BFA1FA6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13700 pso_num=29 pso_serial#=217 2025-05-25 22:05:26.498493 :9BFA1FA7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13700 osp_idx=27 osp_ver=11691 osp_pg=0 (spawn #219175) 2025-05-25 22:05:26.498493 :9BFA1FA8:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 22:05:26.498494 :9BFA1FA9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=73 time=1186498750 2025-05-25 22:05:26.498494 :9BFA1FAA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 22:05:26.498497 :9BFA1FAB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 22:05:26.498497 :9BFA1FAC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 22:05:26.498498 :9BFA1FAD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 22:05:26.498498 :9BFA1FAE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 22:05:26.499058 :9BFA1FAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.499118 :9BFA1FB6:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 22:05:26.499219 :9BFA1FC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.504458 :9BFA1FDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.567474 :9BFA1FE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.569564 :9BFA1FEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.570080 :9BFA1FF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.570102 :9BFA1FF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.570142 :9BFA2000:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 22:05:26.571155 :9BFA200B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.571174 :9BFA200E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.571203 :9BFA2019:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 22:05:26.571740 :9BFA2024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.574074 :9BFA202C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.579267 :9BFA2034:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.584919 :9BFA203C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.586197 :9BFA2044:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.589132 :9BFA204C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.590232 :9BFA2054:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.590239 :9BFA2055:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.000.0016ace1 2025-05-25 22:05:26.593084 :9BFA205D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.593240 :9BFA206C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.593507 :9BFA2077:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.593558 :9BFA207A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-05-25 22:05:26.593572 :9BFA207B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-05-25 22:05:26.595431 :9BFA208A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.595631 :9BFA2092:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.595671 :9BFA2095:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-05-25 22:05:26.595682 :9BFA2096:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-05-25 22:05:26.595846 :9BFA209C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.595863 :9BFA209F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-25 22:05:26.595898 :9BFA20AA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 22:05:26.597976 :9BFA20B5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 22:05:27.801777 :9BFA2455:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 22:05:27.793 2025-05-25 22:05:27.801778 :9BFA2456:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14424 pso_num=29 pso_serial#=218 2025-05-25 22:05:27.801911 :9BFA2457:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14424 (legacy spawn) 2025-05-25 22:05:27.808276 :9BFA2458:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 22:05:27.808441 :9BFA2461:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-25 22:05:27.809231 :9BFA2462:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 14424)