Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250723222122\orcl_w000_18444_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:36091M/63366M, Ph+PgF:41919M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 18444, image: ORACLE.EXE (W000) *** 2025-07-23 22:21:22.193 *** SESSION ID:(177.54309) 2025-07-23 22:21:22.193 *** 2025-07-23 22:21:22.193 Process diagnostic dump for ORACLE.EXE (W000), OS id=18444, pid: 22, proc_ser: 167, sid: 177, sess_ser: 54309 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=4.310795 sec, exc=4.310795 sec, total=4.310795 sec wait times: max=5.000000 sec, heur=7 min 49 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.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.001727 sec, exc=5.001727 sec, total=5.001727 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.001127 sec, exc=5.001127 sec, total=5.001127 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=90 seq_num=91 snap_id=1 wait times: snap=5.013271 sec, exc=5.013271 sec, total=5.013271 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=5.009743 sec, exc=5.009743 sec, total=5.009743 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.014736 sec, exc=5.014736 sec, total=5.014736 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.014742 sec, exc=5.014742 sec, total=5.014742 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.010162 sec, exc=5.010162 sec, total=5.010162 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.003064 sec, exc=5.003064 sec, total=5.003064 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 177 serial 54309 --------------------------------------------------- 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:19:22 - 22:21:22] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-23 22:21:22.193 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 18444, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-23 19:53:13.913971 :B605965D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18440 pso_num=22 pso_serial#=153 2025-07-23 19:53:13.914114 :B605965E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18440 osp_idx=0 osp_ver=436275 osp_pg=0 (spawn #415522) 2025-07-23 19:53:13.914115 :B605965F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 19:53:13.914115 :B6059660:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=96 time=1981169814 2025-07-23 19:53:13.914116 :B6059661:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 19:53:13.914116 :B6059662:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 19:53:13.914117 :B6059663:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 19:53:13.914117 :B6059664:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 19:53:13.914118 :B6059665:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 19:53:13.914628 :B6059666:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 19:53:13.914671 :B605966E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:03:14.828808 :B6066023:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 20:03:15.161657 :B606605F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 20:03:15.157 2025-07-23 20:03:15.161659 :B6066060:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16012 pso_num=22 pso_serial#=154 2025-07-23 20:03:15.161801 :B6066061:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16012 osp_idx=0 osp_ver=436276 osp_pg=0 (spawn #415548) 2025-07-23 20:03:15.161801 :B6066062:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 20:03:15.161802 :B6066063:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=97 time=1981771048 2025-07-23 20:03:15.161802 :B6066064:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 20:03:15.161803 :B6066065:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 20:03:15.161803 :B6066066:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 20:03:15.161803 :B6066067:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 20:03:15.161804 :B6066068:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 20:03:15.162309 :B6066069:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 20:03:15.162354 :B6066070:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:13:16.113277 :B6072FC7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 20:13:16.414933 :B6072FF7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 20:13:16.410 2025-07-23 20:13:16.414935 :B6072FF8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1020 pso_num=22 pso_serial#=155 2025-07-23 20:13:16.415068 :B6072FF9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1020 osp_idx=0 osp_ver=436277 osp_pg=0 (spawn #415570) 2025-07-23 20:13:16.415069 :B6072FFA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 20:13:16.415069 :B6072FFB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=98 time=1982372314 2025-07-23 20:13:16.415070 :B6072FFC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 20:13:16.415070 :B6072FFD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 20:13:16.415071 :B6072FFE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 20:13:16.415072 :B6072FFF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 20:13:16.415072 :B6073000:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 20:13:16.415574 :B6073001:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 20:13:16.415624 :B607300B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:23:17.354604 :B607FC44:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 20:23:17.719001 :B607FCC9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 20:23:17.714 2025-07-23 20:23:17.719004 :B607FCCA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18636 pso_num=22 pso_serial#=156 2025-07-23 20:23:17.719151 :B607FCCB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18636 osp_idx=0 osp_ver=436278 osp_pg=0 (spawn #415593) 2025-07-23 20:23:17.719152 :B607FCCC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 20:23:17.719153 :B607FCCD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=99 time=1982973611 2025-07-23 20:23:17.719153 :B607FCCE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 20:23:17.719154 :B607FCCF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 20:23:17.719154 :B607FCD0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 20:23:17.719154 :B607FCD1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 20:23:17.719155 :B607FCD2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 20:23:17.719662 :B607FCD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 20:23:17.719707 :B607FCDA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:33:18.612078 :B608C519:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 20:33:18.960486 :B608C556:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 20:33:18.955 2025-07-23 20:33:18.960488 :B608C557:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16236 pso_num=22 pso_serial#=157 2025-07-23 20:33:18.960622 :B608C558:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16236 osp_idx=0 osp_ver=436279 osp_pg=0 (spawn #415616) 2025-07-23 20:33:18.960623 :B608C559:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 20:33:18.960625 :B608C55A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=100 time=1983574861 2025-07-23 20:33:18.960625 :B608C55B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 20:33:18.960625 :B608C55C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 20:33:18.960626 :B608C55D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 20:33:18.960626 :B608C55E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 20:33:18.960626 :B608C55F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 20:33:18.961117 :B608C560:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 20:33:18.961163 :B608C567:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:43:19.852899 :B6098E37:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 20:43:20.264091 :B6098E73:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 20:43:20.259 2025-07-23 20:43:20.264093 :B6098E74:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15056 pso_num=22 pso_serial#=158 2025-07-23 20:43:20.264239 :B6098E75:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15056 osp_idx=0 osp_ver=436280 osp_pg=0 (spawn #415638) 2025-07-23 20:43:20.264240 :B6098E76:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 20:43:20.264241 :B6098E77:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=101 time=1984176158 2025-07-23 20:43:20.264241 :B6098E78:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 20:43:20.264241 :B6098E79:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 20:43:20.264242 :B6098E7A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 20:43:20.264242 :B6098E7B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 20:43:20.264242 :B6098E7C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 20:43:20.264742 :B6098E7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 20:43:20.264786 :B6098E86:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:53:21.229525 :B60A56EE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 20:53:21.499780 :B60A571E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 20:53:21.495 2025-07-23 20:53:21.499782 :B60A571F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19172 pso_num=22 pso_serial#=159 2025-07-23 20:53:21.499925 :B60A5720:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19172 osp_idx=0 osp_ver=436281 osp_pg=0 (spawn #415661) 2025-07-23 20:53:21.499925 :B60A5721:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 20:53:21.499926 :B60A5722:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=102 time=1984777392 2025-07-23 20:53:21.499926 :B60A5723:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 20:53:21.499927 :B60A5724:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 20:53:21.499927 :B60A5725:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 20:53:21.499928 :B60A5726:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 20:53:21.499928 :B60A5727:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 20:53:21.500432 :B60A5728:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 20:53:21.500475 :B60A5731:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 20:54:13.995517 :B60A68B8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-23 21:03:22.860769 :B60B229E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-23 21:13:23.785820 :B60BF247:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 21:13:24.149884 :B60BF283:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 21:13:24.145 2025-07-23 21:13:24.149886 :B60BF284:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9092 pso_num=22 pso_serial#=160 2025-07-23 21:13:24.150018 :B60BF285:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9092 osp_idx=0 osp_ver=436282 osp_pg=0 (spawn #415708) 2025-07-23 21:13:24.150019 :B60BF286:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 21:13:24.150019 :B60BF287:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=103 time=1985980048 2025-07-23 21:13:24.150020 :B60BF288:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 21:13:24.150020 :B60BF289:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 21:13:24.150020 :B60BF28A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 21:13:24.150021 :B60BF28B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 21:13:24.150021 :B60BF28C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 21:13:24.150494 :B60BF28D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 21:13:24.150539 :B60BF297:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 21:23:25.043346 :B60CBE86:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 21:23:25.532514 :B60CBEEF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 21:23:25.527 2025-07-23 21:23:25.532516 :B60CBEF0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18064 pso_num=22 pso_serial#=161 2025-07-23 21:23:25.532655 :B60CBEF1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18064 osp_idx=0 osp_ver=436283 osp_pg=0 (spawn #415731) 2025-07-23 21:23:25.532657 :B60CBEF2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 21:23:25.532658 :B60CBEF3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=104 time=1986581423 2025-07-23 21:23:25.532658 :B60CBEF4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 21:23:25.532659 :B60CBEF5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 21:23:25.532659 :B60CBEF6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 21:23:25.532660 :B60CBEF7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 21:23:25.532660 :B60CBEF8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 21:23:25.533144 :B60CBEF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 21:23:25.533192 :B60CBF01:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 21:33:26.434583 :B60D876C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 21:33:26.918164 :B60D87DF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 21:33:26.913 2025-07-23 21:33:26.918166 :B60D87E0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16512 pso_num=22 pso_serial#=162 2025-07-23 21:33:26.918301 :B60D87E1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16512 osp_idx=0 osp_ver=436284 osp_pg=0 (spawn #415754) 2025-07-23 21:33:26.918301 :B60D87E2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 21:33:26.918302 :B60D87E3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=105 time=1987182814 2025-07-23 21:33:26.918302 :B60D87E4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 21:33:26.918302 :B60D87E5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 21:33:26.918303 :B60D87E6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 21:33:26.918303 :B60D87E7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 21:33:26.918304 :B60D87E8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 21:33:26.918864 :B60D87E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 21:33:26.918911 :B60D87F3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 21:43:27.827515 :B60E50F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 21:43:28.251520 :B60E5128:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 21:43:28.246 2025-07-23 21:43:28.251522 :B60E5129:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13480 pso_num=22 pso_serial#=163 2025-07-23 21:43:28.251655 :B60E512A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13480 osp_idx=0 osp_ver=436285 osp_pg=0 (spawn #415776) 2025-07-23 21:43:28.251656 :B60E512B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 21:43:28.251656 :B60E512C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=106 time=1987784142 2025-07-23 21:43:28.251656 :B60E512D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 21:43:28.251657 :B60E512E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 21:43:28.251657 :B60E512F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 21:43:28.251657 :B60E5130:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 21:43:28.251657 :B60E5131:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 21:43:28.252145 :B60E5132:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 21:43:28.252188 :B60E513A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 21:53:29.116195 :B60F193A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 21:53:29.620911 :B60F19A7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 21:53:29.616 2025-07-23 21:53:29.620913 :B60F19A8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4284 pso_num=22 pso_serial#=164 2025-07-23 21:53:29.621050 :B60F19A9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4284 osp_idx=0 osp_ver=436286 osp_pg=0 (spawn #415799) 2025-07-23 21:53:29.621051 :B60F19AA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 21:53:29.621051 :B60F19AB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=107 time=1988385517 2025-07-23 21:53:29.621052 :B60F19AC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 21:53:29.621052 :B60F19AD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 21:53:29.621052 :B60F19AE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 21:53:29.621053 :B60F19AF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 21:53:29.621053 :B60F19B0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 21:53:29.621552 :B60F19B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 21:53:29.621598 :B60F19B8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 22:03:30.527317 :B60FF302:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 22:03:30.875904 :B60FF33E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 22:03:30.871 2025-07-23 22:03:30.875907 :B60FF33F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1020 pso_num=22 pso_serial#=165 2025-07-23 22:03:30.876047 :B60FF340:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1020 osp_idx=0 osp_ver=436287 osp_pg=0 (spawn #415829) 2025-07-23 22:03:30.876047 :B60FF341:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 22:03:30.876048 :B60FF342:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=108 time=1988986767 2025-07-23 22:03:30.876048 :B60FF343:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 22:03:30.876049 :B60FF344:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 22:03:30.876049 :B60FF345:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 22:03:30.876049 :B60FF346:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 22:03:30.876050 :B60FF347:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 22:03:30.876544 :B60FF348:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 22:03:30.876589 :B60FF350:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 22:13:31.790526 :B610CA66:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 22:13:32.127225 :B610CABA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 22:13:32.118 2025-07-23 22:13:32.127227 :B610CABB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2964 pso_num=22 pso_serial#=166 2025-07-23 22:13:32.127371 :B610CABC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2964 (legacy spawn) 2025-07-23 22:13:32.134085 :B610CABD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-23 22:13:32.134264 :B610CAC6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-23 22:13:32.135132 :B610CAC7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-23 22:13:32.235943 :B610CAE1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-23 22:13:32.231 2025-07-23 22:13:32.235945 :B610CAE2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18444 pso_num=22 pso_serial#=167 2025-07-23 22:13:32.236089 :B610CAE3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18444 osp_idx=0 osp_ver=436289 osp_pg=0 (spawn #415851) 2025-07-23 22:13:32.236089 :B610CAE4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-23 22:13:32.236090 :B610CAE5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=109 time=1989588126 2025-07-23 22:13:32.236090 :B610CAE6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-23 22:13:32.236090 :B610CAE7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-23 22:13:32.236091 :B610CAE8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-23 22:13:32.236091 :B610CAE9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-23 22:13:32.236091 :B610CAEA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-23 22:13:32.236597 :B610CAEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-23 22:13:32.236644 :B610CAF2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 18444, W000)