Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504140133\orcl_m003_10780_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:37579M/63366M, Ph+PgF:45111M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 10780, image: ORACLE.EXE (M003) *** 2025-05-04 14:01:33.718 *** 2025-05-04 14:01:33.718 Process diagnostic dump for ORACLE.EXE (M003), OS id=10780, pid: 29, proc_ser: 109, 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-04 14:01:33.718 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 10780, M003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 08:30:45.928215 :927EF2BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-04 08:30:45.928430 :927EF2BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-04 08:30:45.928442 :927EF2BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-04 08:30:45.928647 :927EF2C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-04 08:30:45.928659 :927EF2C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-04 08:30:45.931066 :927EF2C2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 09:00:49.786242 :92814843:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 09:00:49.776 2025-05-04 09:00:49.786243 :92814844:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10368 pso_num=29 pso_serial#=98 2025-05-04 09:00:49.786393 :92814845:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10368 osp_idx=27 osp_ver=10297 osp_pg=0 (spawn #146903) 2025-05-04 09:00:49.786393 :92814846: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-04 09:00:49.786394 :92814847:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=54 time=3620026953 2025-05-04 09:00:49.786394 :92814848:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:00:49.786395 :92814849:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:00:49.786395 :9281484A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:00:49.786396 :9281484B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:00:49.786396 :9281484C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:00:49.786906 :9281484D: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-04 09:00:49.786947 :92814853: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-04 09:00:49.795314 :92814878:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 09:20:28.310759 :9282DF7D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 09:20:28.305 2025-05-04 09:20:28.310761 :9282DF7E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8232 pso_num=29 pso_serial#=99 2025-05-04 09:20:28.310901 :9282DF7F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=8232 osp_idx=27 osp_ver=10298 osp_pg=0 (spawn #146949) 2025-05-04 09:20:28.310902 :9282DF80: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-04 09:20:28.310902 :9282DF81:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=55 time=3621205468 2025-05-04 09:20:28.310903 :9282DF82:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:20:28.310903 :9282DF83:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:20:28.310904 :9282DF84:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:20:28.310904 :9282DF85:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:20:28.310904 :9282DF86:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:20:28.311408 :9282DF87: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-04 09:20:28.311454 :9282DF8E: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-04 09:20:28.317898 :9282DF9B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 10:00:57.553014 :92860015:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 10:00:57.542 2025-05-04 10:00:57.553015 :92860016:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10368 pso_num=29 pso_serial#=100 2025-05-04 10:00:57.553156 :92860019:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10368 osp_idx=27 osp_ver=10299 osp_pg=0 (spawn #147043) 2025-05-04 10:00:57.553156 :9286001A: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-04 10:00:57.553157 :9286001B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=56 time=3623634718 2025-05-04 10:00:57.553157 :9286001C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 10:00:57.553158 :9286001D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 10:00:57.553158 :9286001E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 10:00:57.553159 :9286001F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 10:00:57.553159 :92860020:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 10:00:57.553682 :92860021: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-04 10:00:57.553724 :92860027: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-04 10:00:57.553810 :92860032: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-04 10:00:57.558885 :9286003B: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-04 10:00:57.613492 :92860093: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-04 10:00:57.615704 :9286009B: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-04 10:00:57.617539 :928600A3: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-04 10:00:57.617944 :928600AD: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-04 10:00:57.617969 :928600B0: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-04 10:00:57.618017 :928600BB: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-04 10:00:57.618979 :928600C6: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-04 10:00:57.618995 :928600C9: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-04 10:00:57.619020 :928600D4: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-04 10:00:57.619470 :928600DF: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-04 10:00:57.622171 :928600EF: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-04 10:00:57.627374 :928600FF: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-04 10:00:57.628480 :92860116: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-04 10:00:57.631351 :92860192:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-04 10:00:57.633172 :928601D1: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-04 10:00:57.634559 :928601D9: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-04 10:00:57.637579 :928601E1: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-04 10:00:57.638723 :928601E9: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-04 10:00:57.638733 :928601EA:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.016.00167596 2025-05-04 10:00:57.642114 :928601F2: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-04 10:00:57.642281 :92860201: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-04 10:00:57.642504 :92860209: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-04 10:00:57.642563 :9286020C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-05-04 10:00:57.642579 :9286020D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-05-04 10:00:57.645617 :92860213: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-04 10:00:57.645901 :92860222: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-04 10:00:57.645948 :92860225:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-05-04 10:00:57.645961 :92860226:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-05-04 10:00:57.646134 :9286022C: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-04 10:00:57.646146 :9286022F: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-04 10:00:57.646169 :9286023B: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-04 10:00:57.648275 :92860245:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 10:00:57.684612 :928602EA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 10:00:57.682 2025-05-04 10:00:57.684612 :928602EB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11952 pso_num=29 pso_serial#=101 2025-05-04 10:00:57.684752 :928602F1:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11952 osp_idx=27 osp_ver=10300 osp_pg=0 (spawn #147047) 2025-05-04 10:00:57.684753 :928602F2:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 10:00:57.684753 :928602F3:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=57 time=3623634843 2025-05-04 10:00:57.684754 :928602F4:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 10:00:57.684755 :928602F5:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 10:00:57.684756 :928602F6:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 10:00:57.684756 :928602F7:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 10:00:57.684757 :928602F8:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 10:00:57.685299 :928602F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 10:01:16.863153 :92860F55:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-04 10:01:46.955717 :92861973:db_trace:ksl2.c@2324:kslwtbctx(): [10005:29:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-05-04 10:01:46.955725 :92861974:db_trace:ksl2.c@2611:kslwtectx(): [10005:29:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-05-04 10:01:46.955726 :92861975:db_trace:ksl2.c@2619:kslwtectx(): [10005:29:0] KSL WAIT END wait times (usecs) - snap=9, exc=9, tot=9 2025-05-04 10:01:46.955833 :92861976:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:09.925310 :9293F7D2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:09.922 2025-05-04 13:00:09.925312 :9293F7D3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4660 pso_num=29 pso_serial#=102 2025-05-04 13:00:09.925442 :9293F7D4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4660 (legacy spawn) 2025-05-04 13:00:09.931928 :9293F7D5: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-04 13:00:09.932108 :9293F7DA: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-04 13:00:09.932971 :9293F7DF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:09.962173 :9293F7E2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:09.953 2025-05-04 13:00:09.962175 :9293F7E3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9992 pso_num=29 pso_serial#=103 2025-05-04 13:00:09.962319 :9293F7E4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9992 (legacy spawn) 2025-05-04 13:00:09.968706 :9293F7E5: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-04 13:00:09.968897 :9293F7EE: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-04 13:00:09.969691 :9293F7EF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:11.565502 :9293F86C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:11.557 2025-05-04 13:00:11.565504 :9293F86D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11372 pso_num=29 pso_serial#=104 2025-05-04 13:00:11.565644 :9293F86E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11372 (legacy spawn) 2025-05-04 13:00:11.572018 :9293F86F: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-04 13:00:11.572180 :9293F874: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-04 13:00:11.573022 :9293F879:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:13.120816 :9293F8E3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:13.114 2025-05-04 13:00:13.120818 :9293F8E4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12188 pso_num=29 pso_serial#=105 2025-05-04 13:00:13.120962 :9293F8E5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12188 (legacy spawn) 2025-05-04 13:00:13.127403 :9293F8E6: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-04 13:00:13.127571 :9293F8EB: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-04 13:00:13.128382 :9293F8F0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:13.137276 :9293F8F3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:13.129 2025-05-04 13:00:13.137278 :9293F8F4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9584 pso_num=29 pso_serial#=106 2025-05-04 13:00:13.137411 :9293F8F5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9584 (legacy spawn) 2025-05-04 13:00:13.143844 :9293F8F6: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-04 13:00:13.144005 :9293F8FE: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-04 13:00:13.144786 :9293F900:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:21.712137 :9293FBCB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:21.707 2025-05-04 13:00:21.712140 :9293FBCC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2860 pso_num=29 pso_serial#=107 2025-05-04 13:00:21.712277 :9293FBCD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=2860 osp_idx=27 osp_ver=10306 osp_pg=0 (spawn #147465) 2025-05-04 13:00:21.712277 :9293FBCE: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-04 13:00:21.712278 :9293FBCF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=58 time=3634398875 2025-05-04 13:00:21.712278 :9293FBD0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 13:00:21.712278 :9293FBD1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 13:00:21.712279 :9293FBD2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 13:00:21.712279 :9293FBD3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 13:00:21.712279 :9293FBD4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 13:00:21.712805 :9293FBD5: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-04 13:00:21.712855 :9293FBDC: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-04 13:00:21.717276 :9293FBEE: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-04 13:00:21.739782 :9293FBF6: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-04 13:00:21.754760 :9293FBFE: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-04 13:00:21.784267 :9293FC06: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-04 13:00:21.788712 :9293FC0E: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-04 13:00:21.792496 :9293FC16: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-04 13:00:21.796932 :9293FC1E: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-04 13:00:21.798012 :9293FC26: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-04 13:00:22.920176*:9293FC7E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299635B0 estart=1746334821 eid=16778231 ctx=0x000000077BA4B1C8 cwh=0x00000000299507D0 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-04 13:00:22.920176*:9293FC7F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299635B0 curCtx=0x00000000299D1580 pubTabIdxCnt=0 2025-05-04 13:00:22.920176*:9293FC80:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299635B0 2025-05-04 13:00:22.920176*:9293FC81:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299635B0 2025-05-04 13:00:22.920176*:9293FC82:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299DFCB8 estart=1746334821 eid=16778225 ctx=0x0000000787AD5BF0 cwh=0x0000000029950AA0 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-04 13:00:22.920176*:9293FC83:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299DFCB8 curCtx=0x00000000299D3488 pubTabIdxCnt=0 2025-05-04 13:00:22.920176*:9293FC84:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299DFCB8 2025-05-04 13:00:22.920176*:9293FC85:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299DFCB8 2025-05-04 13:00:22.982675*:9293FC86:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299635B0 curCtx=0x00000000299D1580 xsc->flg4=65792 flags=1 2025-05-04 13:00:22.982675*:9293FC87:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299635B0 2025-05-04 13:00:22.982675*:9293FC88:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299DFCB8 curCtx=0x00000000299D3488 xsc->flg4=65792 flags=2 2025-05-04 13:00:22.982675*:9293FC89:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299DFCB8 2025-05-04 13:00:23.054836 :9293FC8A: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-04 13:00:23.082249 :9293FC92: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-04 13:00:23.082625 :9293FC9A: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-04 13:00:23.082913 :9293FCA2: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-04 13:00:23.082929 :9293FCA5: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-04 13:00:23.082990 :9293FCB1: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-04 13:00:23.085602 :9293FCBF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 13:00:24.145994 :9293FD7B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 13:00:24.136 2025-05-04 13:00:24.145996 :9293FD7C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10700 pso_num=29 pso_serial#=108 2025-05-04 13:00:24.146137 :9293FD7D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10700 (legacy spawn) 2025-05-04 13:00:24.152984 :9293FD7E: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-04 13:00:24.153159 :9293FD83: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-04 13:00:24.154033 :9293FD88:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-04 14:01:29.709838 :9298BDA5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-04 14:01:29.698 2025-05-04 14:01:29.709840 :9298BDA6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10780 pso_num=29 pso_serial#=109 2025-05-04 14:01:29.709982 :9298BDA7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10780 osp_idx=27 osp_ver=10308 osp_pg=0 (spawn #147611) 2025-05-04 14:01:29.709983 :9298BDA8: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-04 14:01:29.709984 :9298BDA9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=59 time=3638066875 2025-05-04 14:01:29.709984 :9298BDAA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 14:01:29.709985 :9298BDAB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 14:01:29.709985 :9298BDAC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 14:01:29.709986 :9298BDAD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 14:01:29.709986 :9298BDAE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 14:01:29.711492 :9298BDAF: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-04 14:01:29.711592 :9298BDB5: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-04 14:01:29.719943 :9298BDC7: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: 10780, M003)