Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250803220944\orcl_w000_17608_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:35778M/63366M, Ph+PgF:41412M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 17608, image: ORACLE.EXE (W000) *** 2025-08-03 22:09:44.144 *** SESSION ID:(177.20115) 2025-08-03 22:09:44.144 *** 2025-08-03 22:09:44.144 Process diagnostic dump for ORACLE.EXE (W000), OS id=17608, pid: 22, proc_ser: 100, sid: 177, sess_ser: 20115 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=2.508693 sec, exc=2.508693 sec, total=2.508693 sec wait times: max=5.000000 sec, heur=47.541916 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.000029 sec since current wait 0: 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.000276 sec, exc=5.000276 sec, total=5.000276 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: 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.009664 sec, exc=5.009664 sec, total=5.009664 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: 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.004616 sec, exc=5.004616 sec, total=5.004616 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.004653 sec, exc=5.004653 sec, total=5.004653 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 5: 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.001708 sec, exc=5.001708 sec, total=5.001708 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: 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.001131 sec, exc=5.001131 sec, total=5.001131 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=53 seq_num=54 snap_id=1 wait times: snap=5.002674 sec, exc=5.002674 sec, total=5.002674 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=52 seq_num=53 snap_id=1 wait times: snap=5.003664 sec, exc=5.003664 sec, total=5.003664 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.001845 sec of elapsed time 9: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x1, filetype=0x2 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000188 sec of elapsed time Sampled Session History of session 177 serial 20115 --------------------------------------------------- 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:07:44 - 22:09:44] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-03 22:09:44.144 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 17608, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-03 19:34:32.246106 :BAE78045:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 19:34:32.246654 :BAE78046: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-08-03 19:34:32.246702 :BAE7804F: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-08-03 19:44:33.070450 :BAE84892:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 19:44:33.481566 :BAE848CD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 19:44:33.477 2025-08-03 19:44:33.481568 :BAE848CE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20136 pso_num=22 pso_serial#=87 2025-08-03 19:44:33.481712 :BAE848CF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20136 osp_idx=0 osp_ver=477264 osp_pg=0 (spawn #452176) 2025-08-03 19:44:33.481712 :BAE848D0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 19:44:33.481713 :BAE848D1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=2930942783 2025-08-03 19:44:33.481713 :BAE848D2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 19:44:33.481715 :BAE848D3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 19:44:33.481716 :BAE848D4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 19:44:33.481716 :BAE848D5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 19:44:33.481716 :BAE848D6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 19:44:33.482220 :BAE848D7: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-08-03 19:44:33.482267 :BAE848DF: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-08-03 19:54:34.260789 :BAE910A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 19:54:34.687410 :BAE910EA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 19:54:34.682 2025-08-03 19:54:34.687412 :BAE910EB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17100 pso_num=22 pso_serial#=88 2025-08-03 19:54:34.687546 :BAE910EC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17100 osp_idx=0 osp_ver=477265 osp_pg=0 (spawn #452199) 2025-08-03 19:54:34.687547 :BAE910ED:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 19:54:34.687547 :BAE910EE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=196 time=2931543986 2025-08-03 19:54:34.687548 :BAE910EF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 19:54:34.687548 :BAE910F0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 19:54:34.687548 :BAE910F1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 19:54:34.687549 :BAE910F2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 19:54:34.687549 :BAE910F3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 19:54:34.688042 :BAE910F4: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-08-03 19:54:34.688087 :BAE910FE: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-08-03 20:04:35.542603 :BAE9DD5C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 20:04:35.798128 :BAE9DD94:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 20:04:35.792 2025-08-03 20:04:35.798130 :BAE9DD95:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20276 pso_num=22 pso_serial#=89 2025-08-03 20:04:35.798302 :BAE9DD96:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20276 osp_idx=0 osp_ver=477266 osp_pg=0 (spawn #452225) 2025-08-03 20:04:35.798303 :BAE9DD97:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 20:04:35.798303 :BAE9DD98:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=2932145111 2025-08-03 20:04:35.798304 :BAE9DD99:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 20:04:35.798305 :BAE9DD9A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 20:04:35.798305 :BAE9DD9B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 20:04:35.798305 :BAE9DD9C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 20:04:35.798305 :BAE9DD9D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 20:04:35.798900 :BAE9DD9E: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-08-03 20:04:35.798952 :BAE9DDA4: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-08-03 20:08:37.179429 :BAEA33EE: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-08-03 20:14:36.873362 :BAEAAC5B: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-08-03 20:24:37.704117 :BAEB74E7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 20:24:38.073293 :BAEB751D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 20:24:38.068 2025-08-03 20:24:38.073295 :BAEB751E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12108 pso_num=22 pso_serial#=90 2025-08-03 20:24:38.073439 :BAEB751F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12108 osp_idx=0 osp_ver=477267 osp_pg=0 (spawn #452270) 2025-08-03 20:24:38.073439 :BAEB7520:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 20:24:38.073440 :BAEB7521:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=198 time=2933347376 2025-08-03 20:24:38.073440 :BAEB7522:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 20:24:38.073441 :BAEB7523:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 20:24:38.073441 :BAEB7524:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 20:24:38.073441 :BAEB7525:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 20:24:38.073442 :BAEB7526:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 20:24:38.073954 :BAEB7527: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-08-03 20:24:38.074006 :BAEB752F: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-08-03 20:34:38.815765 :BAEC3D29:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 20:34:39.150107 :BAEC3D6F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 20:34:39.145 2025-08-03 20:34:39.150110 :BAEC3D70:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14932 pso_num=22 pso_serial#=91 2025-08-03 20:34:39.150264 :BAEC3D71:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14932 osp_idx=0 osp_ver=477268 osp_pg=0 (spawn #452293) 2025-08-03 20:34:39.150265 :BAEC3D72:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 20:34:39.150266 :BAEC3D73:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=199 time=2933948454 2025-08-03 20:34:39.150266 :BAEC3D74:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 20:34:39.150266 :BAEC3D75:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 20:34:39.150267 :BAEC3D76:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 20:34:39.150267 :BAEC3D77:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 20:34:39.150268 :BAEC3D78:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 20:34:39.150811 :BAEC3D79: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-08-03 20:34:39.150859 :BAEC3D7F: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-08-03 20:44:40.019330 :BAED05F4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 20:44:40.321096 :BAED0639:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 20:44:40.316 2025-08-03 20:44:40.321098 :BAED063A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17392 pso_num=22 pso_serial#=92 2025-08-03 20:44:40.321241 :BAED063B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17392 osp_idx=0 osp_ver=477269 osp_pg=0 (spawn #452315) 2025-08-03 20:44:40.321242 :BAED063C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 20:44:40.321242 :BAED063D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=200 time=2934549626 2025-08-03 20:44:40.321243 :BAED063E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 20:44:40.321243 :BAED063F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 20:44:40.321243 :BAED0640:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 20:44:40.321243 :BAED0641:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 20:44:40.321244 :BAED0642:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 20:44:40.321757 :BAED0643: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-08-03 20:44:40.321801 :BAED064A: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-08-03 20:54:41.111710 :BAEDCFFC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 20:54:41.429377 :BAEDD03C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 20:54:41.424 2025-08-03 20:54:41.429379 :BAEDD03D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18108 pso_num=22 pso_serial#=93 2025-08-03 20:54:41.429526 :BAEDD03E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18108 osp_idx=0 osp_ver=477270 osp_pg=0 (spawn #452338) 2025-08-03 20:54:41.429526 :BAEDD03F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 20:54:41.429527 :BAEDD040:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=201 time=2935150736 2025-08-03 20:54:41.429527 :BAEDD041:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 20:54:41.429528 :BAEDD042:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 20:54:41.429528 :BAEDD043:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 20:54:41.429528 :BAEDD044:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 20:54:41.429529 :BAEDD045:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 20:54:41.430043 :BAEDD046: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-08-03 20:54:41.430091 :BAEDD050: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-08-03 21:04:42.244766 :BAEE9CD3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 21:04:42.582360 :BAEE9D0B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 21:04:42.577 2025-08-03 21:04:42.582362 :BAEE9D0C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16748 pso_num=22 pso_serial#=94 2025-08-03 21:04:42.582505 :BAEE9D0D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16748 osp_idx=0 osp_ver=477271 osp_pg=0 (spawn #452363) 2025-08-03 21:04:42.582506 :BAEE9D0E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 21:04:42.582507 :BAEE9D0F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=202 time=2935751892 2025-08-03 21:04:42.582507 :BAEE9D10:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 21:04:42.582508 :BAEE9D11:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 21:04:42.582508 :BAEE9D12:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 21:04:42.582509 :BAEE9D13:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 21:04:42.582509 :BAEE9D14:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 21:04:42.583007 :BAEE9D15: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-08-03 21:04:42.583054 :BAEE9D1B: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-08-03 21:14:43.361872 :BAEF6E8D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 21:14:43.757292 :BAEF6ED4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 21:14:43.757 2025-08-03 21:14:43.757294 :BAEF6ED5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19340 pso_num=22 pso_serial#=95 2025-08-03 21:14:43.757424 :BAEF6ED6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19340 osp_idx=0 osp_ver=477272 osp_pg=0 (spawn #452386) 2025-08-03 21:14:43.757424 :BAEF6ED7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 21:14:43.757425 :BAEF6ED8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=203 time=2936353064 2025-08-03 21:14:43.757425 :BAEF6ED9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 21:14:43.757425 :BAEF6EDA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 21:14:43.757426 :BAEF6EDB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 21:14:43.757426 :BAEF6EDC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 21:14:43.757426 :BAEF6EDD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 21:14:43.757917 :BAEF6EDE: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-08-03 21:14:43.757964 :BAEF6EE5: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-08-03 21:24:44.572630 :BAF03DC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 21:24:44.889835 :BAF03DFB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 21:24:44.885 2025-08-03 21:24:44.889837 :BAF03DFC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9092 pso_num=22 pso_serial#=96 2025-08-03 21:24:44.889972 :BAF03DFD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9092 osp_idx=0 osp_ver=477273 osp_pg=0 (spawn #452409) 2025-08-03 21:24:44.889973 :BAF03DFE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 21:24:44.889973 :BAF03DFF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=204 time=2936954189 2025-08-03 21:24:44.889974 :BAF03E00:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 21:24:44.889974 :BAF03E01:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 21:24:44.889974 :BAF03E02:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 21:24:44.889974 :BAF03E03:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 21:24:44.889975 :BAF03E04:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 21:24:44.890456 :BAF03E05: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-08-03 21:24:44.890502 :BAF03E0D: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-08-03 21:34:45.627419 :BAF10A19:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 21:34:46.007484 :BAF10A8B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 21:34:46.002 2025-08-03 21:34:46.007486 :BAF10A8C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16340 pso_num=22 pso_serial#=97 2025-08-03 21:34:46.007627 :BAF10A8D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16340 osp_idx=0 osp_ver=477274 osp_pg=0 (spawn #452432) 2025-08-03 21:34:46.007628 :BAF10A8E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 21:34:46.007628 :BAF10A8F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=205 time=2937555314 2025-08-03 21:34:46.007629 :BAF10A90:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 21:34:46.007629 :BAF10A91:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 21:34:46.007629 :BAF10A92:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 21:34:46.007629 :BAF10A93:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 21:34:46.007630 :BAF10A94:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 21:34:46.008170 :BAF10A95: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-08-03 21:34:46.008218 :BAF10A9C: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-08-03 21:44:46.779211 :BAF1D6F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 21:44:47.159025 :BAF1D735:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 21:44:47.153 2025-08-03 21:44:47.159027 :BAF1D736:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15000 pso_num=22 pso_serial#=98 2025-08-03 21:44:47.159164 :BAF1D737:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15000 osp_idx=0 osp_ver=477275 osp_pg=0 (spawn #452454) 2025-08-03 21:44:47.159165 :BAF1D738:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 21:44:47.159165 :BAF1D739:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=206 time=2938156470 2025-08-03 21:44:47.159166 :BAF1D73A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 21:44:47.159166 :BAF1D73B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 21:44:47.159166 :BAF1D73C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 21:44:47.159166 :BAF1D73D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 21:44:47.159167 :BAF1D73E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 21:44:47.159666 :BAF1D73F: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-08-03 21:44:47.159712 :BAF1D746: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-08-03 21:54:48.025974 :BAF2A42B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 21:54:48.391238 :BAF2A46F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 21:54:48.386 2025-08-03 21:54:48.391240 :BAF2A470:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19512 pso_num=22 pso_serial#=99 2025-08-03 21:54:48.391377 :BAF2A471:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19512 osp_idx=0 osp_ver=477276 osp_pg=0 (spawn #452477) 2025-08-03 21:54:48.391378 :BAF2A472:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 21:54:48.391378 :BAF2A473:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=207 time=2938757704 2025-08-03 21:54:48.391378 :BAF2A474:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 21:54:48.391379 :BAF2A475:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 21:54:48.391379 :BAF2A476:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 21:54:48.391379 :BAF2A477:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 21:54:48.391379 :BAF2A478:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 21:54:48.391868 :BAF2A479: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-08-03 21:54:48.391913 :BAF2A480: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-08-03 22:04:49.295722 :BAF370CA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-03 22:04:49.691198 :BAF37107:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 22:04:49.686 2025-08-03 22:04:49.691200 :BAF37108:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17608 pso_num=22 pso_serial#=100 2025-08-03 22:04:49.691337 :BAF37109:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17608 osp_idx=0 osp_ver=477277 osp_pg=0 (spawn #452502) 2025-08-03 22:04:49.691337 :BAF3710A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-03 22:04:49.691338 :BAF3710B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=208 time=2939359001 2025-08-03 22:04:49.691338 :BAF3710C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 22:04:49.691338 :BAF3710D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 22:04:49.691339 :BAF3710E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 22:04:49.691339 :BAF3710F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 22:04:49.691340 :BAF37110:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 22:04:49.691906 :BAF37111: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-08-03 22:04:49.691952 :BAF37119: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-08-03 22:08:56.606769 :BAF3C9BB: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 17608, W000)