Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250527081348\orcl_w000_15096_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:37288M/63366M, Ph+PgF:44247M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15096, image: ORACLE.EXE (W000) *** 2025-05-27 08:13:48.897 *** SESSION ID:(201.10375) 2025-05-27 08:13:48.897 *** 2025-05-27 08:13:48.897 Process diagnostic dump for ORACLE.EXE (W000), OS id=15096, pid: 25, proc_ser: 115, sid: 201, sess_ser: 10375 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=1.551665 sec, exc=1.551665 sec, total=1.551665 sec wait times: max=5.000000 sec, heur=5 min 32 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=66 seq_num=67 snap_id=1 wait times: snap=5.008273 sec, exc=5.008273 sec, total=5.008273 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=65 seq_num=66 snap_id=1 wait times: snap=5.014715 sec, exc=5.014715 sec, total=5.014715 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=64 seq_num=65 snap_id=1 wait times: snap=5.014734 sec, exc=5.014734 sec, total=5.014734 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=63 seq_num=64 snap_id=1 wait times: snap=5.010144 sec, exc=5.010144 sec, total=5.010144 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=62 seq_num=63 snap_id=1 wait times: snap=5.008305 sec, exc=5.008305 sec, total=5.008305 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 5: 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.000419 sec, exc=5.000419 sec, total=5.000419 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=60 seq_num=61 snap_id=1 wait times: snap=5.000479 sec, exc=5.000479 sec, total=5.000479 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: 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.012546 sec, exc=5.012546 sec, total=5.012546 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 8: 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.014715 sec, exc=5.014715 sec, total=5.014715 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 9: 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.004617 sec, exc=5.004617 sec, total=5.004617 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time Sampled Session History of session 201 serial 10375 --------------------------------------------------- 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, 08:11:48 - 08:13:48] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-27 08:13:48.897 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15096, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-27 05:37:56.863634 :9C8E1673:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 05:37:56.863634 :9C8E1674:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 05:37:56.864169 :9C8E1675: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-05-27 05:37:56.864219 :9C8E167F: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-05-27 05:47:57.916265 :9C8EDB94:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 05:47:58.217806 :9C8EDBF8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 05:47:58.213 2025-05-27 05:47:58.217808 :9C8EDBF9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13616 pso_num=25 pso_serial#=102 2025-05-27 05:47:58.217946 :9C8EDBFA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13616 osp_idx=23 osp_ver=121991 osp_pg=0 (spawn #223566) 2025-05-27 05:47:58.217947 :9C8EDBFB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 05:47:58.217947 :9C8EDBFC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=1300650469 2025-05-27 05:47:58.217948 :9C8EDBFD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 05:47:58.217948 :9C8EDBFE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 05:47:58.217949 :9C8EDBFF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 05:47:58.217949 :9C8EDC00:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 05:47:58.217949 :9C8EDC01:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 05:47:58.218439 :9C8EDC02: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-05-27 05:47:58.218486 :9C8EDC09: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-05-27 05:57:59.210873 :9C8FA269:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 05:57:59.465574 :9C8FA2B3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 05:57:59.461 2025-05-27 05:57:59.465576 :9C8FA2B4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13992 pso_num=25 pso_serial#=103 2025-05-27 05:57:59.465709 :9C8FA2B5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13992 osp_idx=23 osp_ver=121992 osp_pg=0 (spawn #223589) 2025-05-27 05:57:59.465709 :9C8FA2B6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 05:57:59.465710 :9C8FA2B7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=39 time=1301251719 2025-05-27 05:57:59.465710 :9C8FA2B8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 05:57:59.465710 :9C8FA2B9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 05:57:59.465710 :9C8FA2BA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 05:57:59.465711 :9C8FA2BB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 05:57:59.465711 :9C8FA2BC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 05:57:59.466215 :9C8FA2BD: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-05-27 05:57:59.466261 :9C8FA2C6: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-05-27 06:08:00.417714 :9C90730E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 06:08:00.719249 :9C907369:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 06:08:00.714 2025-05-27 06:08:00.719250 :9C90736A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11420 pso_num=25 pso_serial#=104 2025-05-27 06:08:00.719380 :9C90736B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11420 osp_idx=23 osp_ver=121993 osp_pg=0 (spawn #223615) 2025-05-27 06:08:00.719381 :9C90736C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 06:08:00.719381 :9C90736D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=1301852969 2025-05-27 06:08:00.719382 :9C90736E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 06:08:00.719382 :9C90736F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 06:08:00.719382 :9C907370:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 06:08:00.719383 :9C907371:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 06:08:00.719383 :9C907372:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 06:08:00.719878 :9C907373: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-05-27 06:08:00.719920 :9C90737B: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-05-27 06:18:01.553873 :9C913799:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 06:18:01.982595 :9C913809:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 06:18:01.978 2025-05-27 06:18:01.982596 :9C91380A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15044 pso_num=25 pso_serial#=105 2025-05-27 06:18:01.982738 :9C91380B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15044 osp_idx=23 osp_ver=121994 osp_pg=0 (spawn #223637) 2025-05-27 06:18:01.982739 :9C91380C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 06:18:01.982739 :9C91380D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=1302454235 2025-05-27 06:18:01.982739 :9C91380E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 06:18:01.982740 :9C91380F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 06:18:01.982740 :9C913810:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 06:18:01.982740 :9C913811:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 06:18:01.982741 :9C913812:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 06:18:01.983239 :9C913813: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-05-27 06:18:01.983284 :9C91381A: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-05-27 06:28:02.840001 :9C91FD44:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 06:28:03.282195 :9C91FD94:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 06:28:03.277 2025-05-27 06:28:03.282197 :9C91FD95:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14684 pso_num=25 pso_serial#=106 2025-05-27 06:28:03.282335 :9C91FD96:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14684 osp_idx=23 osp_ver=121995 osp_pg=0 (spawn #223660) 2025-05-27 06:28:03.282336 :9C91FD97:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 06:28:03.282336 :9C91FD98:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=1303055532 2025-05-27 06:28:03.282336 :9C91FD99:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 06:28:03.282336 :9C91FD9A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 06:28:03.282337 :9C91FD9B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 06:28:03.282337 :9C91FD9C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 06:28:03.282338 :9C91FD9D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 06:28:03.282842 :9C91FD9E: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-05-27 06:28:03.282887 :9C91FDA5: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-05-27 06:38:04.155701 :9C92C2A3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 06:38:04.488348 :9C92C2DB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 06:38:04.484 2025-05-27 06:38:04.488350 :9C92C2DC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13912 pso_num=25 pso_serial#=107 2025-05-27 06:38:04.488537 :9C92C2DD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13912 osp_idx=23 osp_ver=121996 osp_pg=0 (spawn #223683) 2025-05-27 06:38:04.488538 :9C92C2DE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 06:38:04.488539 :9C92C2DF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=1303656750 2025-05-27 06:38:04.488539 :9C92C2E0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 06:38:04.488540 :9C92C2E1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 06:38:04.488540 :9C92C2E2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 06:38:04.488541 :9C92C2E3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 06:38:04.488541 :9C92C2E4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 06:38:04.489050 :9C92C2E5: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-05-27 06:38:04.489097 :9C92C2ED: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-05-27 06:48:05.427490 :9C93888C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 06:48:05.777173 :9C9388C4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 06:48:05.772 2025-05-27 06:48:05.777175 :9C9388C5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14148 pso_num=25 pso_serial#=108 2025-05-27 06:48:05.777312 :9C9388C6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14148 osp_idx=23 osp_ver=121997 osp_pg=0 (spawn #223706) 2025-05-27 06:48:05.777313 :9C9388C7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 06:48:05.777313 :9C9388C8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=44 time=1304258032 2025-05-27 06:48:05.777314 :9C9388C9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 06:48:05.777314 :9C9388CA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 06:48:05.777314 :9C9388CB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 06:48:05.777315 :9C9388CC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 06:48:05.777315 :9C9388CD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 06:48:05.777810 :9C9388CE: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-05-27 06:48:05.777861 :9C9388D8: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-05-27 06:58:06.719074 :9C944D64:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 06:58:07.098825 :9C944DA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 06:58:07.093 2025-05-27 06:58:07.098827 :9C944DAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11412 pso_num=25 pso_serial#=109 2025-05-27 06:58:07.099012 :9C944DAB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11412 osp_idx=23 osp_ver=121998 osp_pg=0 (spawn #223729) 2025-05-27 06:58:07.099013 :9C944DAC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 06:58:07.099014 :9C944DAD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=45 time=1304859360 2025-05-27 06:58:07.099014 :9C944DAE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 06:58:07.099014 :9C944DAF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 06:58:07.099015 :9C944DB0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 06:58:07.099015 :9C944DB1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 06:58:07.099016 :9C944DB2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 06:58:07.099547 :9C944DB3: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-05-27 06:58:07.099594 :9C944DBD: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-05-27 07:05:30.673076 :9C94E13D: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-05-27 07:08:08.402745 :9C951C1C: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-05-27 07:18:09.201270 :9C95E102:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 07:18:09.690093 :9C95E144:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 07:18:09.685 2025-05-27 07:18:09.690095 :9C95E145:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11372 pso_num=25 pso_serial#=110 2025-05-27 07:18:09.690232 :9C95E146:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11372 osp_idx=23 osp_ver=121999 osp_pg=0 (spawn #223776) 2025-05-27 07:18:09.690233 :9C95E147:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 07:18:09.690233 :9C95E148:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=46 time=1306061938 2025-05-27 07:18:09.690233 :9C95E149:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 07:18:09.690234 :9C95E14A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 07:18:09.690234 :9C95E14B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 07:18:09.690234 :9C95E14C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 07:18:09.690235 :9C95E14D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 07:18:09.690742 :9C95E14E: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-05-27 07:18:09.690797 :9C95E154: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-05-27 07:28:10.636927 :9C96A8AA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 07:28:11.136102 :9C96A8E2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 07:28:11.131 2025-05-27 07:28:11.136104 :9C96A8E3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14608 pso_num=25 pso_serial#=111 2025-05-27 07:28:11.136241 :9C96A8E4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14608 osp_idx=23 osp_ver=122000 osp_pg=0 (spawn #223799) 2025-05-27 07:28:11.136241 :9C96A8E5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 07:28:11.136242 :9C96A8E6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=47 time=1306663391 2025-05-27 07:28:11.136242 :9C96A8E7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 07:28:11.136243 :9C96A8E8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 07:28:11.136243 :9C96A8E9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 07:28:11.136243 :9C96A8EA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 07:28:11.136243 :9C96A8EB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 07:28:11.136735 :9C96A8EC: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-05-27 07:28:11.136778 :9C96A8F3: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-05-27 07:38:12.048801 :9C976F1B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 07:38:12.490991 :9C976F5C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 07:38:12.486 2025-05-27 07:38:12.490993 :9C976F5D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15144 pso_num=25 pso_serial#=112 2025-05-27 07:38:12.491124 :9C976F5E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15144 osp_idx=23 osp_ver=122001 osp_pg=0 (spawn #223822) 2025-05-27 07:38:12.491125 :9C976F5F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 07:38:12.491125 :9C976F60:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=48 time=1307264750 2025-05-27 07:38:12.491126 :9C976F61:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 07:38:12.491126 :9C976F62:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 07:38:12.491126 :9C976F63:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 07:38:12.491127 :9C976F64:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 07:38:12.491127 :9C976F65:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 07:38:12.491619 :9C976F66: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-05-27 07:38:12.491663 :9C976F6E: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-05-27 07:48:13.372957 :9C983404:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 07:48:13.955695 :9C983446:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 07:48:13.951 2025-05-27 07:48:13.955698 :9C983447:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2472 pso_num=25 pso_serial#=113 2025-05-27 07:48:13.955832 :9C983448:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2472 osp_idx=23 osp_ver=122002 osp_pg=0 (spawn #223844) 2025-05-27 07:48:13.955832 :9C983449:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 07:48:13.955833 :9C98344A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=49 time=1307866204 2025-05-27 07:48:13.955833 :9C98344B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 07:48:13.955834 :9C98344C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 07:48:13.955834 :9C98344D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 07:48:13.955834 :9C98344E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 07:48:13.955835 :9C98344F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 07:48:13.956325 :9C983450: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-05-27 07:48:13.956377 :9C98345A: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-05-27 07:58:14.871973 :9C98F9CF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 07:58:15.392359 :9C98FA0A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 07:58:15.387 2025-05-27 07:58:15.392362 :9C98FA0B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14884 pso_num=25 pso_serial#=114 2025-05-27 07:58:15.392501 :9C98FA0C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14884 osp_idx=23 osp_ver=122003 osp_pg=0 (spawn #223867) 2025-05-27 07:58:15.392502 :9C98FA0D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 07:58:15.392502 :9C98FA0E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=50 time=1308467641 2025-05-27 07:58:15.392502 :9C98FA0F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 07:58:15.392503 :9C98FA10:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 07:58:15.392503 :9C98FA11:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 07:58:15.392503 :9C98FA12:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 07:58:15.392504 :9C98FA13:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 07:58:15.393008 :9C98FA14: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-05-27 07:58:15.393052 :9C98FA1C: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-05-27 08:08:16.351798 :9C99C552:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-27 08:08:16.825195 :9C99C599:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-27 08:08:16.820 2025-05-27 08:08:16.825197 :9C99C59A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15096 pso_num=25 pso_serial#=115 2025-05-27 08:08:16.825339 :9C99C59B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15096 osp_idx=23 osp_ver=122004 osp_pg=0 (spawn #223893) 2025-05-27 08:08:16.825340 :9C99C59C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-27 08:08:16.825340 :9C99C59D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=51 time=1309069079 2025-05-27 08:08:16.825341 :9C99C59E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-27 08:08:16.825341 :9C99C59F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-27 08:08:16.825342 :9C99C5A0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-27 08:08:16.825342 :9C99C5A1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-27 08:08:16.825342 :9C99C5A2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-27 08:08:16.825828 :9C99C5A3: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-05-27 08:08:16.825876 :9C99C5AB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 15096, W000)