Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250624220006\orcl_w000_18228_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:36287M/63366M, Ph+PgF:42461M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 18228, image: ORACLE.EXE (W000) *** 2025-06-24 22:00:06.680 *** SESSION ID:(201.44849) 2025-06-24 22:00:06.680 *** 2025-06-24 22:00:06.680 Process diagnostic dump for ORACLE.EXE (W000), OS id=18228, pid: 25, proc_ser: 186, sid: 201, sess_ser: 44849 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=80 seq_num=81 snap_id=1 wait times: snap=4.656526 sec, exc=4.656526 sec, total=4.656526 sec wait times: max=5.000000 sec, heur=6 min 40 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.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=5.009005 sec, exc=5.009005 sec, total=5.009005 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=5.007590 sec, exc=5.007590 sec, total=5.007590 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=77 seq_num=78 snap_id=1 wait times: snap=5.003611 sec, exc=5.003611 sec, total=5.003611 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.004623 sec, exc=5.004623 sec, total=5.004623 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: 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.014723 sec, exc=5.014723 sec, total=5.014723 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: 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.010153 sec, exc=5.010153 sec, total=5.010153 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 6: 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.009705 sec, exc=5.009705 sec, total=5.009705 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 7: 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.000298 sec, exc=5.000298 sec, total=5.000298 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: 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.007390 sec, exc=5.007390 sec, total=5.007390 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: 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.013496 sec, exc=5.013496 sec, total=5.013496 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time Sampled Session History of session 201 serial 44849 --------------------------------------------------- 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, 21:58:06 - 22:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-24 22:00:06.680 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 18228, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-24 20:46:01.522996 :A924B906:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-24 20:46:01.523264 :A924B907:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-24 20:46:01.523281 :A924B908:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-24 20:46:01.523504 :A924B909:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-24 20:46:01.523518 :A924B90A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-24 20:46:01.523730 :A924B90B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-24 20:46:01.523742 :A924B90C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-24 20:46:01.523958 :A924B90D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-24 20:46:01.523972 :A924B90E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-24 20:46:01.524177 :A924B90F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-24 20:46:01.524190 :A924B910:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-24 20:46:01.526549 :A924B911:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:46:18.262967 :A924BE9E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:46:18.251 2025-06-24 20:46:18.262969 :A924BE9F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14796 pso_num=25 pso_serial#=173 2025-06-24 20:46:18.263104 :A924BEA0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14796 osp_idx=23 osp_ver=167820 osp_pg=0 (spawn #319003) 2025-06-24 20:46:18.263105 :A924BEA1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:46:18.263106 :A924BEA2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=170 time=3773721454 2025-06-24 20:46:18.263106 :A924BEA3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:46:18.263107 :A924BEA4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:46:18.263107 :A924BEA5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:46:18.263107 :A924BEA6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:46:18.263108 :A924BEA7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:46:18.263641 :A924BEA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:46:48.317568 :A924C963:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:47:18.347603 :A924D32A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:47:18.336 2025-06-24 20:47:18.347605 :A924D32B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15676 pso_num=25 pso_serial#=174 2025-06-24 20:47:18.347736 :A924D32C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15676 osp_idx=23 osp_ver=167821 osp_pg=0 (spawn #319005) 2025-06-24 20:47:18.347736 :A924D32D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:47:18.347737 :A924D32E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=171 time=3773781532 2025-06-24 20:47:18.347737 :A924D32F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:47:18.347737 :A924D330:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:47:18.347738 :A924D331:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:47:18.347738 :A924D332:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:47:18.347738 :A924D333:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:47:18.348224 :A924D334:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:47:48.447972 :A924DC98:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:48:18.460884 :A924E65A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:48:18.450 2025-06-24 20:48:18.460886 :A924E65B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17352 pso_num=25 pso_serial#=175 2025-06-24 20:48:18.461023 :A924E65C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17352 osp_idx=23 osp_ver=167822 osp_pg=0 (spawn #319007) 2025-06-24 20:48:18.461024 :A924E65D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:48:18.461024 :A924E65E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=172 time=3773841641 2025-06-24 20:48:18.461024 :A924E65F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:48:18.461025 :A924E660:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:48:18.461025 :A924E661:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:48:18.461025 :A924E662:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:48:18.461026 :A924E663:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:48:18.461489 :A924E664:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:48:48.566638 :A924F001:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:49:18.576436 :A924F9FE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:49:18.565 2025-06-24 20:49:18.576438 :A924F9FF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12732 pso_num=25 pso_serial#=176 2025-06-24 20:49:18.576577 :A924FA00:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12732 osp_idx=23 osp_ver=167823 osp_pg=0 (spawn #319009) 2025-06-24 20:49:18.576578 :A924FA01:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:49:18.576579 :A924FA02:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=173 time=3773901766 2025-06-24 20:49:18.576579 :A924FA03:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:49:18.576580 :A924FA04:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:49:18.576580 :A924FA05:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:49:18.576580 :A924FA06:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:49:18.576581 :A924FA07:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:49:18.577061 :A924FA08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:49:43.596429 :A925024E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-24 20:50:13.709198 :A9250BB1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:50:18.672702 :A9250DE8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:50:18.662 2025-06-24 20:50:18.672705 :A9250DE9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17048 pso_num=25 pso_serial#=177 2025-06-24 20:50:18.672834 :A9250DEA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17048 osp_idx=23 osp_ver=167824 osp_pg=0 (spawn #319011) 2025-06-24 20:50:18.672834 :A9250DEB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:50:18.672834 :A9250DEC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=174 time=3773961860 2025-06-24 20:50:18.672835 :A9250DED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:50:18.672835 :A9250DEE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:50:18.672835 :A9250DEF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:50:18.672835 :A9250DF0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:50:18.672836 :A9250DF1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:50:18.673399 :A9250DF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:50:48.726665 :A925173B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:51:18.742415 :A92520FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:51:18.730 2025-06-24 20:51:18.742417 :A92520FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18184 pso_num=25 pso_serial#=178 2025-06-24 20:51:18.742535 :A92520FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18184 osp_idx=23 osp_ver=167825 osp_pg=0 (spawn #319013) 2025-06-24 20:51:18.742536 :A92520FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:51:18.742536 :A92520FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=175 time=3774021922 2025-06-24 20:51:18.742536 :A92520FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:51:18.742537 :A9252100:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:51:18.742537 :A9252101:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:51:18.742538 :A9252102:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:51:18.742538 :A9252103:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:51:18.743025 :A9252104:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:51:48.833430 :A9252B46:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:52:18.849927 :A92534F5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:52:18.839 2025-06-24 20:52:18.849929 :A92534F6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14056 pso_num=25 pso_serial#=179 2025-06-24 20:52:18.850066 :A92534F7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14056 osp_idx=23 osp_ver=167826 osp_pg=0 (spawn #319015) 2025-06-24 20:52:18.850066 :A92534F8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:52:18.850067 :A92534F9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=176 time=3774082032 2025-06-24 20:52:18.850067 :A92534FA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:52:18.850068 :A92534FB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:52:18.850068 :A92534FC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:52:18.850068 :A92534FD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:52:18.850068 :A92534FE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:52:18.850547 :A92534FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:52:48.968644 :A9253F05:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 20:53:19.014568 :A92548D2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 20:53:19.009 2025-06-24 20:53:19.014570 :A92548D3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16492 pso_num=25 pso_serial#=180 2025-06-24 20:53:19.014714 :A92548D4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16492 osp_idx=23 osp_ver=167827 osp_pg=0 (spawn #319018) 2025-06-24 20:53:19.014715 :A92548D5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 20:53:19.014715 :A92548D6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=177 time=3774142204 2025-06-24 20:53:19.014716 :A92548D7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 20:53:19.014716 :A92548D8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 20:53:19.014716 :A92548D9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 20:53:19.014717 :A92548DA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 20:53:19.014717 :A92548DB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 20:53:19.015221 :A92548DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 20:53:19.015269 :A92548E4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-24 21:03:19.965181 :A9260EF4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 21:03:20.313619 :A9260F55:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 21:03:20.308 2025-06-24 21:03:20.313622 :A9260F56:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17548 pso_num=25 pso_serial#=181 2025-06-24 21:03:20.313759 :A9260F57:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17548 osp_idx=23 osp_ver=167828 osp_pg=0 (spawn #319044) 2025-06-24 21:03:20.313760 :A9260F58:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 21:03:20.313760 :A9260F59:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=178 time=3774743500 2025-06-24 21:03:20.313761 :A9260F5A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 21:03:20.313761 :A9260F5B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 21:03:20.313761 :A9260F5C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 21:03:20.313762 :A9260F5D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 21:03:20.313762 :A9260F5E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 21:03:20.314269 :A9260F5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 21:03:20.314314 :A9260F67:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-24 21:13:21.188775 :A926D98E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 21:13:21.552718 :A926DA06:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 21:13:21.548 2025-06-24 21:13:21.552720 :A926DA07:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15680 pso_num=25 pso_serial#=182 2025-06-24 21:13:21.552866 :A926DA08:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15680 osp_idx=23 osp_ver=167829 osp_pg=0 (spawn #319066) 2025-06-24 21:13:21.552866 :A926DA09:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 21:13:21.552867 :A926DA0A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=179 time=3775344750 2025-06-24 21:13:21.552867 :A926DA0B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 21:13:21.552868 :A926DA0C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 21:13:21.552868 :A926DA0D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 21:13:21.552868 :A926DA0E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 21:13:21.552869 :A926DA0F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 21:13:21.553366 :A926DA10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 21:13:21.553414 :A926DA16:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-24 21:23:22.491016 :A927A120:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 21:23:22.786840 :A927A157:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 21:23:22.782 2025-06-24 21:23:22.786841 :A927A158:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13628 pso_num=25 pso_serial#=183 2025-06-24 21:23:22.786981 :A927A159:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13628 osp_idx=23 osp_ver=167830 osp_pg=0 (spawn #319089) 2025-06-24 21:23:22.786982 :A927A15A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 21:23:22.786982 :A927A15B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=180 time=3775945985 2025-06-24 21:23:22.786983 :A927A15C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 21:23:22.786983 :A927A15D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 21:23:22.786983 :A927A15E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 21:23:22.786984 :A927A15F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 21:23:22.786984 :A927A160:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 21:23:22.787475 :A927A161:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 21:23:22.787519 :A927A169:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-24 21:33:23.655217 :A928663C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 21:33:24.050534 :A928668B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 21:33:24.046 2025-06-24 21:33:24.050536 :A928668C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18424 pso_num=25 pso_serial#=184 2025-06-24 21:33:24.050679 :A928668D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18424 osp_idx=23 osp_ver=167831 osp_pg=0 (spawn #319110) 2025-06-24 21:33:24.050680 :A928668E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 21:33:24.050680 :A928668F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=181 time=3776547235 2025-06-24 21:33:24.050681 :A9286690:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 21:33:24.050681 :A9286691:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 21:33:24.050682 :A9286692:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 21:33:24.050682 :A9286693:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 21:33:24.050682 :A9286694:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 21:33:24.051163 :A9286695:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 21:33:24.051208 :A928669D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-24 21:43:24.931876 :A9292B66:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 21:43:25.217851 :A9292BA1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 21:43:25.213 2025-06-24 21:43:25.217853 :A9292BA2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14508 pso_num=25 pso_serial#=185 2025-06-24 21:43:25.217984 :A9292BA3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14508 osp_idx=23 osp_ver=167832 osp_pg=0 (spawn #319132) 2025-06-24 21:43:25.217985 :A9292BA4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 21:43:25.217986 :A9292BA5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=182 time=3777148407 2025-06-24 21:43:25.217986 :A9292BA6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 21:43:25.217986 :A9292BA7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 21:43:25.217987 :A9292BA8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 21:43:25.217987 :A9292BA9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 21:43:25.217988 :A9292BAA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 21:43:25.218466 :A9292BAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 21:43:25.218511 :A9292BB2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-24 21:53:26.075393 :A929F027:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 21:53:26.439547 :A929F07B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 21:53:26.434 2025-06-24 21:53:26.439549 :A929F07C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18228 pso_num=25 pso_serial#=186 2025-06-24 21:53:26.439691 :A929F07D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18228 osp_idx=23 osp_ver=167833 osp_pg=0 (spawn #319155) 2025-06-24 21:53:26.439692 :A929F07E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 21:53:26.439693 :A929F07F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=183 time=3777749625 2025-06-24 21:53:26.439693 :A929F080:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 21:53:26.439694 :A929F081:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 21:53:26.439694 :A929F082:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 21:53:26.439694 :A929F083:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 21:53:26.439695 :A929F084:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 21:53:26.440244 :A929F085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 21:53:26.440287 :A929F08D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 18228, W000)