Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250429221649\orcl_w000_10456_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:37668M/63366M, Ph+PgF:45276M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 10456, image: ORACLE.EXE (W000) *** 2025-04-29 22:16:49.896 *** SESSION ID:(193.58553) 2025-04-29 22:16:49.896 *** 2025-04-29 22:16:49.896 Process diagnostic dump for ORACLE.EXE (W000), OS id=10456, pid: 24, proc_ser: 244, sid: 193, sess_ser: 58553 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=1.266001 sec, exc=1.266001 sec, total=1.266001 sec wait times: max=5.000000 sec, heur=6 min 16 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000031 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.005141 sec, exc=5.005141 sec, total=5.005141 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.003388 sec, exc=5.003388 sec, total=5.003388 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.000324 sec, exc=5.000324 sec, total=5.000324 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.007794 sec, exc=5.007794 sec, total=5.007794 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.004532 sec, exc=5.004532 sec, total=5.004532 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.001784 sec, exc=5.001784 sec, total=5.001784 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.010155 sec, exc=5.010155 sec, total=5.010155 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.009209 sec, exc=5.009209 sec, total=5.009209 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.007224 sec, exc=5.007224 sec, total=5.007224 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=66 seq_num=67 snap_id=1 wait times: snap=5.001287 sec, exc=5.001287 sec, total=5.001287 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 193 serial 58553 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 22:14:49 - 22:16:49] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-29 22:16:49.896 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 10456, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-29 21:58:24.718650 :90872538:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11100 osp_idx=22 osp_ver=195561 osp_pg=0 (spawn #131765) 2025-04-29 21:58:24.718651 :90872539:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 21:58:24.718651 :9087253A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=195 time=3234694203 2025-04-29 21:58:24.718652 :9087253B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 21:58:24.718652 :9087253C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 21:58:24.718652 :9087253D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 21:58:24.718653 :9087253E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 21:58:24.718653 :9087253F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 21:58:24.719211 :90872540:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 21:58:54.829679 :90873028:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 21:59:24.850645 :908739F6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 21:59:24.839 2025-04-29 21:59:24.850646 :908739F7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9672 pso_num=24 pso_serial#=233 2025-04-29 21:59:24.850782 :908739F8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9672 osp_idx=22 osp_ver=195562 osp_pg=0 (spawn #131767) 2025-04-29 21:59:24.850782 :908739F9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 21:59:24.850783 :908739FA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=196 time=3234754328 2025-04-29 21:59:24.850783 :908739FB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 21:59:24.850784 :908739FC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 21:59:24.850784 :908739FD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 21:59:24.850784 :908739FE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 21:59:24.850784 :908739FF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 21:59:24.851263 :90873A00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 21:59:54.924009 :908743D1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:00.130276 :9087459C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:00.121 2025-04-29 22:00:00.130278 :9087459D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8784 pso_num=24 pso_serial#=234 2025-04-29 22:00:00.130416 :9087459E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8784 osp_idx=22 osp_ver=195563 osp_pg=0 (spawn #131769) 2025-04-29 22:00:00.130417 :9087459F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 22:00:00.130417 :908745A0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=197 time=3234789609 2025-04-29 22:00:00.130418 :908745A1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 22:00:00.130418 :908745A2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 22:00:00.130418 :908745A3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 22:00:00.130419 :908745A4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 22:00:00.130419 :908745A5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 22:00:00.130933 :908745A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.130977 :908745AD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:00.131054 :908745C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.135005 :908745D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.136159 :908745D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.136183 :908745DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.136222 :908745E6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-29 22:00:00.138128 :908745F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.138153 :908745F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.138191 :908745FF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-29 22:00:00.138654 :9087460A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.141136 :90874612:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.146384 :9087461A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.150834 :90874622:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.151747 :9087462A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.153210 :90874632:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.156079 :9087463A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.157374 :90874642:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.160472 :9087464A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.163929 :90874652:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.164320 :90874661:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.164365 :90874664:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-29 22:00:00.164378 :90874665:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-29 22:00:00.166407 :9087466B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.166454 :9087466E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-29 22:00:00.166465 :9087466F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-29 22:00:00.168339 :90874675:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.168377 :90874678:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-29 22:00:00.168387 :90874679:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-29 22:00:00.168550 :9087467F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.168564 :90874682:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-29 22:00:00.168601 :9087468D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-29 22:00:00.170224 :90874697:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:02.127355 :90874773:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:02.121 2025-04-29 22:00:02.127357 :90874774:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3772 pso_num=24 pso_serial#=235 2025-04-29 22:00:02.127491 :90874775:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3772 osp_idx=22 osp_ver=195564 osp_pg=0 (spawn #131770) 2025-04-29 22:00:02.127491 :90874776:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 22:00:02.127492 :90874777:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=198 time=3234791609 2025-04-29 22:00:02.127492 :90874778:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 22:00:02.127492 :90874779:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 22:00:02.127493 :9087477A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 22:00:02.127493 :9087477B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 22:00:02.127493 :9087477C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 22:00:02.127966 :9087477D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.133001 :9087479C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.133044 :9087479F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-04-29 22:00:02.142553 :908747EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.144566 :9087481A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.149621 :90874837:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.150423 :90874881:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.156111 :908749C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.156263 :908749D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.157103 :908749DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.157292 :908749E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-04-29 22:00:02.158784 :908749E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.158825 :908749E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-29 22:00:02.158903 :908749F3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:02.262858 :90874AE3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-29 22:00:04.013561 :90874ED6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-29 22:00:04.013561*:90874EDC:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1745935204 eid=16779514 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-29 22:00:04.013561*:90874EDD:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-04-29 22:00:04.013561*:90874EDE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-04-29 22:00:04.013561*:90874EDF:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-04-29 22:00:04.013561*:90874EE0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-04-29 22:00:04.013561*:90874EE1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-04-29 22:00:04.015740 :90874EE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:04.015776 :90874EE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-04-29 22:00:04.022790 :90874EEF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:04.023601 :90874EF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:04.024619 :90874EFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:04.024818 :90874F07:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-04-29 22:00:04.026177 :90874F13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:04.026212 :90874F16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-29 22:00:04.026250 :90874F1E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:04.026978 :90874F2C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:04.027381 :90874F2D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:06.549091 :908750FF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:06.544 2025-04-29 22:00:06.549093 :90875100:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9944 pso_num=24 pso_serial#=236 2025-04-29 22:00:06.549229 :90875101:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9944 osp_idx=22 osp_ver=195565 osp_pg=0 (spawn #131776) 2025-04-29 22:00:06.549230 :90875102:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 22:00:06.549230 :90875103:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=199 time=3234796031 2025-04-29 22:00:06.549231 :90875104:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 22:00:06.549231 :90875105:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 22:00:06.549231 :90875106:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 22:00:06.549232 :90875107:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 22:00:06.549232 :90875108:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 22:00:06.549729 :9087510A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 22:00:06.549773 :90875111:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:06.684793 :90875143:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:06.685173 :90875156:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:10.858266 :90875454:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:10.843 2025-04-29 22:00:10.858268 :90875455:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6428 pso_num=24 pso_serial#=237 2025-04-29 22:00:10.858400 :90875456:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6428 (legacy spawn) 2025-04-29 22:00:10.864812 :90875457:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:10.865003 :9087545C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:10.865782 :90875461:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:10.874440 :90875464:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:10.859 2025-04-29 22:00:10.874441 :90875465:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10028 pso_num=24 pso_serial#=238 2025-04-29 22:00:10.874617 :90875466:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10028 (legacy spawn) 2025-04-29 22:00:10.881057 :90875467:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:10.881226 :90875470:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:10.882064 :90875471:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:14.110718 :90875582:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:14.098 2025-04-29 22:00:14.110720 :90875583:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5008 pso_num=24 pso_serial#=239 2025-04-29 22:00:14.110857 :90875584:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=5008 (legacy spawn) 2025-04-29 22:00:14.117308 :90875585:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:14.117482 :9087558A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:14.118272 :9087558F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:14.126883 :90875592:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:14.113 2025-04-29 22:00:14.126885 :90875593:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12040 pso_num=24 pso_serial#=240 2025-04-29 22:00:14.127018 :90875594:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12040 (legacy spawn) 2025-04-29 22:00:14.146068 :90875595:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:14.146237 :9087559E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:14.147069 :9087559F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:18.454006 :908756CE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:18.446 2025-04-29 22:00:18.454008 :908756CF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6768 pso_num=24 pso_serial#=241 2025-04-29 22:00:18.454185 :908756D0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6768 (legacy spawn) 2025-04-29 22:00:18.460703 :908756D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:18.460919 :908756D6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:18.461751 :908756E7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:30.739400 :90875BA8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:30.726 2025-04-29 22:00:30.739402 :90875BA9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8644 pso_num=24 pso_serial#=242 2025-04-29 22:00:30.739542 :90875BAA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8644 (legacy spawn) 2025-04-29 22:00:30.745967 :90875BAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-29 22:00:30.746148 :90875BB0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-04-29 22:00:30.746940 :90875BB5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:00:32.058775 :90875C42:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:00:32.054 2025-04-29 22:00:32.058777 :90875C43:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11876 pso_num=24 pso_serial#=243 2025-04-29 22:00:32.058912 :90875C44:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11876 osp_idx=22 osp_ver=195572 osp_pg=0 (spawn #131778) 2025-04-29 22:00:32.058913 :90875C45:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 22:00:32.058913 :90875C46:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=200 time=3234821546 2025-04-29 22:00:32.058914 :90875C47:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 22:00:32.058914 :90875C48:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 22:00:32.058915 :90875C49:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 22:00:32.058915 :90875C4A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 22:00:32.058916 :90875C4B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 22:00:32.059421 :90875C4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 22:00:32.059468 :90875C53:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-29 22:10:32.879932 :90882FC6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-29 22:10:33.196931 :90883003:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-29 22:10:33.192 2025-04-29 22:10:33.196933 :90883004:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10456 pso_num=24 pso_serial#=244 2025-04-29 22:10:33.197068 :90883005:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10456 osp_idx=22 osp_ver=195573 osp_pg=0 (spawn #131806) 2025-04-29 22:10:33.197069 :90883006:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-29 22:10:33.197069 :90883007:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=201 time=3235422671 2025-04-29 22:10:33.197069 :90883008:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-29 22:10:33.197070 :90883009:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-29 22:10:33.197070 :9088300A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-29 22:10:33.197071 :9088300B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-29 22:10:33.197071 :9088300C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-29 22:10:33.197566 :9088300D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-29 22:10:33.197609 :90883015:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 10456, W000)