Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816140745\orcl_w000_21244_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:35432M/63366M, Ph+PgF:40738M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 21244, image: ORACLE.EXE (W000) *** 2025-08-16 14:07:45.469 *** SESSION ID:(201.12543) 2025-08-16 14:07:45.469 *** 2025-08-16 14:07:45.469 Process diagnostic dump for ORACLE.EXE (W000), OS id=21244, pid: 25, proc_ser: 176, sid: 201, sess_ser: 12543 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=63 seq_num=64 snap_id=1 wait times: snap=1.173518 sec, exc=1.173518 sec, total=1.173518 sec wait times: max=5.000000 sec, heur=26.208081 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=62 seq_num=63 snap_id=1 wait times: snap=5.015250 sec, exc=5.015250 sec, total=5.015250 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.003329 sec, exc=5.003329 sec, total=5.003329 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=60 seq_num=61 snap_id=1 wait times: snap=5.000411 sec, exc=5.000411 sec, total=5.000411 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=5.011001 sec, exc=5.011001 sec, total=5.011001 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=58 seq_num=59 snap_id=1 wait times: snap=5.004449 sec, exc=5.004449 sec, total=5.004449 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.001923 sec of elapsed time 5: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x1, filetype=0x2 wait_id=57 seq_num=58 snap_id=1 wait times: snap=0.000157 sec, exc=0.000157 sec, total=0.000157 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000233 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=4.135485 sec, exc=4.135485 sec, total=4.135485 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=55 seq_num=56 snap_id=1 wait times: snap=5.014738 sec, exc=5.014738 sec, total=5.014738 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.004655 sec, exc=5.004655 sec, total=5.004655 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=53 seq_num=54 snap_id=1 wait times: snap=5.004622 sec, exc=5.004622 sec, total=5.004622 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 201 serial 12543 --------------------------------------------------- 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, 14:05:45 - 14:07:45] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 14:07:45.469 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 21244, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 11:32:19.859902 :C078C465: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-08-16 11:32:19.859948 :C078C46C: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-08-16 11:42:20.753043 :C0798D74:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 11:42:21.314877 :C0798DB2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 11:42:21.310 2025-08-16 11:42:21.314879 :C0798DB3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21080 pso_num=25 pso_serial#=163 2025-08-16 11:42:21.315023 :C0798DB4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21080 osp_idx=23 osp_ver=243545 osp_pg=0 (spawn #494387) 2025-08-16 11:42:21.315024 :C0798DB5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 11:42:21.315024 :C0798DB6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=4025210626 2025-08-16 11:42:21.315025 :C0798DB7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 11:42:21.315025 :C0798DB8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 11:42:21.315025 :C0798DB9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 11:42:21.315025 :C0798DBA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 11:42:21.315026 :C0798DBB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 11:42:21.315531 :C0798DBC: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-08-16 11:42:21.315575 :C0798DC4: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-08-16 11:52:22.128455 :C07A5366:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 11:52:22.727047 :C07A53A1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 11:52:22.722 2025-08-16 11:52:22.727049 :C07A53A2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21828 pso_num=25 pso_serial#=164 2025-08-16 11:52:22.727192 :C07A53A3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21828 osp_idx=23 osp_ver=243546 osp_pg=0 (spawn #494409) 2025-08-16 11:52:22.727193 :C07A53A4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 11:52:22.727193 :C07A53A5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=2 time=4025812033 2025-08-16 11:52:22.727194 :C07A53A6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 11:52:22.727194 :C07A53A7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 11:52:22.727194 :C07A53A8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 11:52:22.727194 :C07A53A9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 11:52:22.727195 :C07A53AA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 11:52:22.727691 :C07A53AB: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-08-16 11:52:22.727736 :C07A53B2: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-08-16 12:02:23.693833 :C07B1C8F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 12:02:24.167498 :C07B1CBE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 12:02:24.163 2025-08-16 12:02:24.167500 :C07B1CBF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20768 pso_num=25 pso_serial#=165 2025-08-16 12:02:24.167639 :C07B1CC0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20768 osp_idx=23 osp_ver=243547 osp_pg=0 (spawn #494434) 2025-08-16 12:02:24.167640 :C07B1CC1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 12:02:24.167641 :C07B1CC2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=4026413470 2025-08-16 12:02:24.167642 :C07B1CC3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 12:02:24.167642 :C07B1CC4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 12:02:24.167642 :C07B1CC5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 12:02:24.167643 :C07B1CC6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 12:02:24.167643 :C07B1CC7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 12:02:24.168138 :C07B1CC8: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-08-16 12:02:24.168184 :C07B1CD0: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-08-16 12:07:18.541601 :C07B8308:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-16 12:12:25.553866 :C07BE90C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-16 12:22:26.448626 :C07CB0EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 12:22:26.843949 :C07CB11B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 12:22:26.839 2025-08-16 12:22:26.843951 :C07CB11C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21124 pso_num=25 pso_serial#=166 2025-08-16 12:22:26.844091 :C07CB11D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21124 osp_idx=23 osp_ver=243548 osp_pg=0 (spawn #494479) 2025-08-16 12:22:26.844092 :C07CB11E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 12:22:26.844093 :C07CB11F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=4 time=4027616158 2025-08-16 12:22:26.844093 :C07CB120:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 12:22:26.844093 :C07CB121:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 12:22:26.844094 :C07CB122:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 12:22:26.844094 :C07CB123:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 12:22:26.844094 :C07CB124:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 12:22:26.844590 :C07CB125: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-08-16 12:22:26.844635 :C07CB12D: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-08-16 12:32:27.711497 :C07D77BE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 12:32:28.200544 :C07D77EB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 12:32:28.195 2025-08-16 12:32:28.200546 :C07D77EC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22204 pso_num=25 pso_serial#=167 2025-08-16 12:32:28.200688 :C07D77ED:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22204 osp_idx=23 osp_ver=243549 osp_pg=0 (spawn #494502) 2025-08-16 12:32:28.200689 :C07D77EE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 12:32:28.200689 :C07D77EF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=5 time=4028217501 2025-08-16 12:32:28.200689 :C07D77F0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 12:32:28.200689 :C07D77F1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 12:32:28.200689 :C07D77F2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 12:32:28.200690 :C07D77F3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 12:32:28.200690 :C07D77F4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 12:32:28.201195 :C07D77F5: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-08-16 12:32:28.201242 :C07D77FC: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-08-16 12:42:29.177433 :C07E412B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 12:42:29.510256 :C07E415B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 12:42:29.505 2025-08-16 12:42:29.510258 :C07E415C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20904 pso_num=25 pso_serial#=168 2025-08-16 12:42:29.510404 :C07E415D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20904 osp_idx=23 osp_ver=243550 osp_pg=0 (spawn #494525) 2025-08-16 12:42:29.510404 :C07E415E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 12:42:29.510405 :C07E415F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=6 time=4028818814 2025-08-16 12:42:29.510405 :C07E4160:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 12:42:29.510406 :C07E4161:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 12:42:29.510406 :C07E4162:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 12:42:29.510406 :C07E4163:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 12:42:29.510406 :C07E4164:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 12:42:29.510908 :C07E4165: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-08-16 12:42:29.510955 :C07E416D: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-08-16 12:52:30.423694 :C07F0D08:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 12:52:30.819056 :C07F0D44:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 12:52:30.814 2025-08-16 12:52:30.819058 :C07F0D45:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20816 pso_num=25 pso_serial#=169 2025-08-16 12:52:30.819199 :C07F0D46:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20816 osp_idx=23 osp_ver=243551 osp_pg=0 (spawn #494547) 2025-08-16 12:52:30.819200 :C07F0D47:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 12:52:30.819200 :C07F0D48:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=4029420126 2025-08-16 12:52:30.819201 :C07F0D49:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 12:52:30.819201 :C07F0D4A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 12:52:30.819201 :C07F0D4B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 12:52:30.819201 :C07F0D4C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 12:52:30.819202 :C07F0D4D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 12:52:30.819699 :C07F0D4E: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-08-16 12:52:30.819743 :C07F0D56: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-08-16 13:02:31.722137 :C07FDAA9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 13:02:32.039312 :C07FDAD9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 13:02:32.035 2025-08-16 13:02:32.039314 :C07FDADA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22256 pso_num=25 pso_serial#=170 2025-08-16 13:02:32.039452 :C07FDADB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22256 osp_idx=23 osp_ver=243552 osp_pg=0 (spawn #494572) 2025-08-16 13:02:32.039453 :C07FDADC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 13:02:32.039454 :C07FDADD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=4030021345 2025-08-16 13:02:32.039454 :C07FDADE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 13:02:32.039455 :C07FDADF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 13:02:32.039455 :C07FDAE0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 13:02:32.039455 :C07FDAE1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 13:02:32.039456 :C07FDAE2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 13:02:32.039953 :C07FDAE3: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-08-16 13:02:32.039998 :C07FDAEA: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-08-16 13:12:32.921229 :C080B006:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 13:12:33.279836 :C080B037:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 13:12:33.275 2025-08-16 13:12:33.279837 :C080B038:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19096 pso_num=25 pso_serial#=171 2025-08-16 13:12:33.279977 :C080B039:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19096 osp_idx=23 osp_ver=243553 osp_pg=0 (spawn #494596) 2025-08-16 13:12:33.279978 :C080B03A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 13:12:33.279978 :C080B03B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=4030622579 2025-08-16 13:12:33.279979 :C080B03C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 13:12:33.279979 :C080B03D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 13:12:33.279979 :C080B03E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 13:12:33.279979 :C080B03F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 13:12:33.279980 :C080B040:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 13:12:33.280472 :C080B041: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-08-16 13:12:33.280513 :C080B048: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-08-16 13:22:34.173536 :C0817BEE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 13:22:34.600105 :C0817C1C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 13:22:34.595 2025-08-16 13:22:34.600107 :C0817C1D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22272 pso_num=25 pso_serial#=172 2025-08-16 13:22:34.600241 :C0817C1E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22272 osp_idx=23 osp_ver=243554 osp_pg=0 (spawn #494618) 2025-08-16 13:22:34.600242 :C0817C1F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 13:22:34.600243 :C0817C20:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=10 time=4031223908 2025-08-16 13:22:34.600243 :C0817C21:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 13:22:34.600243 :C0817C22:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 13:22:34.600244 :C0817C23:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 13:22:34.600244 :C0817C24:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 13:22:34.600245 :C0817C25:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 13:22:34.600780 :C0817C26: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-08-16 13:22:34.600829 :C0817C2D: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-08-16 13:32:35.536473 :C08248A1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 13:32:35.869577 :C08248DB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 13:32:35.864 2025-08-16 13:32:35.869578 :C08248DC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22452 pso_num=25 pso_serial#=173 2025-08-16 13:32:35.869723 :C08248DD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22452 osp_idx=23 osp_ver=243555 osp_pg=0 (spawn #494641) 2025-08-16 13:32:35.869724 :C08248DE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 13:32:35.869724 :C08248DF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=11 time=4031825173 2025-08-16 13:32:35.869724 :C08248E0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 13:32:35.869725 :C08248E1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 13:32:35.869725 :C08248E2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 13:32:35.869726 :C08248E3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 13:32:35.869726 :C08248E4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 13:32:35.870245 :C08248E5: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-08-16 13:32:35.870292 :C08248EC: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-08-16 13:42:36.698957 :C08314EF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 13:42:37.203767 :C0831521:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 13:42:37.199 2025-08-16 13:42:37.203769 :C0831522:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9904 pso_num=25 pso_serial#=174 2025-08-16 13:42:37.203903 :C0831523:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9904 osp_idx=23 osp_ver=243556 osp_pg=0 (spawn #494664) 2025-08-16 13:42:37.203904 :C0831524:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 13:42:37.203904 :C0831525:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=12 time=4032426517 2025-08-16 13:42:37.203905 :C0831526:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 13:42:37.203905 :C0831527:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 13:42:37.203906 :C0831528:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 13:42:37.203906 :C0831529:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 13:42:37.203906 :C083152A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 13:42:37.204387 :C083152B: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-08-16 13:42:37.204431 :C0831532: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-08-16 13:52:38.033205 :C083E14A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 13:52:38.444306 :C083E184:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 13:52:38.440 2025-08-16 13:52:38.444308 :C083E185:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19052 pso_num=25 pso_serial#=175 2025-08-16 13:52:38.444449 :C083E186:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19052 osp_idx=23 osp_ver=243557 osp_pg=0 (spawn #494686) 2025-08-16 13:52:38.444450 :C083E187:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 13:52:38.444450 :C083E188:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=4033027751 2025-08-16 13:52:38.444451 :C083E189:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 13:52:38.444451 :C083E18A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 13:52:38.444451 :C083E18B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 13:52:38.444452 :C083E18C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 13:52:38.444452 :C083E18D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 13:52:38.444947 :C083E18E: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-08-16 13:52:38.445003 :C083E196: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-08-16 14:02:39.327470 :C084AF24:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 14:02:39.707398 :C084AF55:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 14:02:39.702 2025-08-16 14:02:39.707399 :C084AF56:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21244 pso_num=25 pso_serial#=176 2025-08-16 14:02:39.707535 :C084AF57:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21244 osp_idx=23 osp_ver=243558 osp_pg=0 (spawn #494711) 2025-08-16 14:02:39.707536 :C084AF58:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 14:02:39.707536 :C084AF59:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=4033629017 2025-08-16 14:02:39.707537 :C084AF5A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 14:02:39.707537 :C084AF5B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 14:02:39.707538 :C084AF5C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 14:02:39.707538 :C084AF5D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 14:02:39.707538 :C084AF5E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 14:02:39.708052 :C084AF5F: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-08-16 14:02:39.708094 :C084AF69: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-08-16 14:07:19.265645 :C085149D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 21244, W000)