Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250620220005\orcl_w000_14356_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:36446M/63366M, Ph+PgF:42847M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14356, image: ORACLE.EXE (W000) *** 2025-06-20 22:00:05.291 *** SESSION ID:(201.34353) 2025-06-20 22:00:05.291 *** 2025-06-20 22:00:05.291 Process diagnostic dump for ORACLE.EXE (W000), OS id=14356, pid: 25, proc_ser: 226, sid: 201, sess_ser: 34353 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=1.130362 sec, exc=1.130362 sec, total=1.130362 sec wait times: max=5.000000 sec, heur=8 min 46 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=105 seq_num=106 snap_id=1 wait times: snap=5.001329 sec, exc=5.001329 sec, total=5.001329 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=104 seq_num=105 snap_id=1 wait times: snap=5.000284 sec, exc=5.000284 sec, total=5.000284 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=103 seq_num=104 snap_id=1 wait times: snap=5.000236 sec, exc=5.000236 sec, total=5.000236 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=102 seq_num=103 snap_id=1 wait times: snap=5.003765 sec, exc=5.003765 sec, total=5.003765 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000039 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=101 seq_num=102 snap_id=1 wait times: snap=5.014721 sec, exc=5.014721 sec, total=5.014721 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=5.004607 sec, exc=5.004607 sec, total=5.004607 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=5.009740 sec, exc=5.009740 sec, total=5.009740 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=98 seq_num=99 snap_id=1 wait times: snap=5.003256 sec, exc=5.003256 sec, total=5.003256 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=97 seq_num=98 snap_id=1 wait times: snap=5.000453 sec, exc=5.000453 sec, total=5.000453 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.005797 sec, exc=5.005797 sec, total=5.005797 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 201 serial 34353 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 21:58:05 - 22:00:05] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-20 22:00:05.291 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14356, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-20 19:21:00.229197 :A7583746:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 19:21:00.229724 :A7583747:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 19:21:00.229769 :A758374F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 19:31:01.042199 :A758FEDD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 19:31:01.359461 :A758FF50:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 19:31:01.354 2025-06-20 19:31:01.359464 :A758FF51:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17152 pso_num=25 pso_serial#=213 2025-06-20 19:31:01.359606 :A758FF52:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17152 osp_idx=23 osp_ver=163015 osp_pg=0 (spawn #305466) 2025-06-20 19:31:01.359606 :A758FF53:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 19:31:01.359606 :A758FF54:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=3423604547 2025-06-20 19:31:01.359607 :A758FF55:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 19:31:01.359607 :A758FF56:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 19:31:01.359607 :A758FF57:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 19:31:01.359608 :A758FF58:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 19:31:01.359608 :A758FF59:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 19:31:01.360093 :A758FF5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 19:31:01.360143 :A758FF61:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 19:41:02.293722 :A759C87F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 19:41:02.532842 :A759C8BA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 19:41:02.528 2025-06-20 19:41:02.532844 :A759C8BB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16776 pso_num=25 pso_serial#=214 2025-06-20 19:41:02.532979 :A759C8BC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16776 osp_idx=23 osp_ver=163016 osp_pg=0 (spawn #305489) 2025-06-20 19:41:02.532980 :A759C8BD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 19:41:02.532980 :A759C8BE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=208 time=3424205719 2025-06-20 19:41:02.532981 :A759C8BF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 19:41:02.532981 :A759C8C0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 19:41:02.532982 :A759C8C1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 19:41:02.532982 :A759C8C2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 19:41:02.532982 :A759C8C3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 19:41:02.533463 :A759C8C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 19:41:02.533507 :A759C8CC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 19:51:03.473456 :A75A9144:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 19:51:03.681276 :A75A9196:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 19:51:03.676 2025-06-20 19:51:03.681278 :A75A9197:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15972 pso_num=25 pso_serial#=215 2025-06-20 19:51:03.681421 :A75A9198:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15972 osp_idx=23 osp_ver=163017 osp_pg=0 (spawn #305512) 2025-06-20 19:51:03.681422 :A75A9199:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 19:51:03.681423 :A75A919A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=209 time=3424806875 2025-06-20 19:51:03.681423 :A75A919B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 19:51:03.681423 :A75A919C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 19:51:03.681423 :A75A919D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 19:51:03.681424 :A75A919E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 19:51:03.681424 :A75A919F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 19:51:03.681924 :A75A91A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 19:51:03.681970 :A75A91A8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 20:01:04.637074 :A75B5B42:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 20:01:04.861736 :A75B5B87:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 20:01:04.857 2025-06-20 20:01:04.861738 :A75B5B88:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15784 pso_num=25 pso_serial#=216 2025-06-20 20:01:04.861877 :A75B5B89:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15784 osp_idx=23 osp_ver=163018 osp_pg=0 (spawn #305536) 2025-06-20 20:01:04.861878 :A75B5B8A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 20:01:04.861878 :A75B5B8B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=3425408047 2025-06-20 20:01:04.861879 :A75B5B8C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 20:01:04.861879 :A75B5B8D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 20:01:04.861879 :A75B5B8E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 20:01:04.861880 :A75B5B8F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 20:01:04.861880 :A75B5B90:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 20:01:04.862374 :A75B5B91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 20:01:04.862421 :A75B5B99:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 20:08:13.339950 :A75BEF7C: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-06-20 20:11:06.046866 :A75C2CB5: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-06-20 20:21:06.957237 :A75CF40F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 20:21:07.258648 :A75CF44A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 20:21:07.254 2025-06-20 20:21:07.258651 :A75CF44B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14644 pso_num=25 pso_serial#=217 2025-06-20 20:21:07.258829 :A75CF44C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14644 osp_idx=23 osp_ver=163019 osp_pg=0 (spawn #305582) 2025-06-20 20:21:07.258830 :A75CF44D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 20:21:07.258830 :A75CF44E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=3426610454 2025-06-20 20:21:07.258831 :A75CF44F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 20:21:07.258831 :A75CF450:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 20:21:07.258831 :A75CF451:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 20:21:07.258832 :A75CF452:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 20:21:07.258832 :A75CF453:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 20:21:07.259337 :A75CF454:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 20:21:07.259382 :A75CF45C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 20:31:08.221830 :A75DBC24:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 20:31:08.570211 :A75DBC5E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 20:31:08.565 2025-06-20 20:31:08.570213 :A75DBC5F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15036 pso_num=25 pso_serial#=218 2025-06-20 20:31:08.570375 :A75DBC60:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15036 osp_idx=23 osp_ver=163020 osp_pg=0 (spawn #305604) 2025-06-20 20:31:08.570376 :A75DBC61:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 20:31:08.570376 :A75DBC62:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=3427211766 2025-06-20 20:31:08.570377 :A75DBC63:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 20:31:08.570377 :A75DBC64:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 20:31:08.570378 :A75DBC65:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 20:31:08.570378 :A75DBC66:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 20:31:08.570379 :A75DBC67:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 20:31:08.570925 :A75DBC68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 20:31:08.570972 :A75DBC70:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 20:41:09.590714 :A75E8402:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 20:41:09.751703 :A75E843C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 20:41:09.747 2025-06-20 20:41:09.751705 :A75E843D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15728 pso_num=25 pso_serial#=219 2025-06-20 20:41:09.751849 :A75E843E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15728 osp_idx=23 osp_ver=163021 osp_pg=0 (spawn #305627) 2025-06-20 20:41:09.751850 :A75E843F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 20:41:09.751851 :A75E8440:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=3427812938 2025-06-20 20:41:09.751851 :A75E8441:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 20:41:09.751851 :A75E8442:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 20:41:09.751852 :A75E8443:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 20:41:09.751852 :A75E8444:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 20:41:09.751852 :A75E8445:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 20:41:09.752355 :A75E8446:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 20:41:09.752398 :A75E844E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 20:51:10.683068 :A75F4C09:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 20:51:10.922386 :A75F4C42:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 20:51:10.917 2025-06-20 20:51:10.922388 :A75F4C43:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11896 pso_num=25 pso_serial#=220 2025-06-20 20:51:10.922523 :A75F4C44:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11896 osp_idx=23 osp_ver=163022 osp_pg=0 (spawn #305650) 2025-06-20 20:51:10.922523 :A75F4C45:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 20:51:10.922524 :A75F4C46:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=3428414110 2025-06-20 20:51:10.922524 :A75F4C47:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 20:51:10.922524 :A75F4C48:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 20:51:10.922525 :A75F4C49:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 20:51:10.922525 :A75F4C4A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 20:51:10.922525 :A75F4C4B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 20:51:10.923070 :A75F4C4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 20:51:10.923115 :A75F4C54:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 21:01:11.764324 :A76015BB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 21:01:12.191144 :A7601602:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 21:01:12.186 2025-06-20 21:01:12.191146 :A7601603:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18292 pso_num=25 pso_serial#=221 2025-06-20 21:01:12.191291 :A7601604:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18292 osp_idx=23 osp_ver=163023 osp_pg=0 (spawn #305674) 2025-06-20 21:01:12.191292 :A7601605:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 21:01:12.191292 :A7601606:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=3429015375 2025-06-20 21:01:12.191293 :A7601607:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 21:01:12.191293 :A7601608:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 21:01:12.191294 :A7601609:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 21:01:12.191294 :A760160A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 21:01:12.191294 :A760160B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 21:01:12.191806 :A760160C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 21:01:12.191850 :A7601613:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 21:11:13.025453 :A760E760:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 21:11:13.452310 :A760E799:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 21:11:13.447 2025-06-20 21:11:13.452313 :A760E79A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13792 pso_num=25 pso_serial#=222 2025-06-20 21:11:13.452464 :A760E79B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13792 osp_idx=23 osp_ver=163024 osp_pg=0 (spawn #305698) 2025-06-20 21:11:13.452465 :A760E79C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 21:11:13.452465 :A760E79D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=3429616641 2025-06-20 21:11:13.452466 :A760E79E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 21:11:13.452467 :A760E79F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 21:11:13.452467 :A760E7A0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 21:11:13.452468 :A760E7A1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 21:11:13.452468 :A760E7A2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 21:11:13.453009 :A760E7A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 21:11:13.453058 :A760E7AA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 21:21:14.414323 :A761AF72:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 21:21:14.669068 :A761AFAC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 21:21:14.664 2025-06-20 21:21:14.669070 :A761AFAD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8164 pso_num=25 pso_serial#=223 2025-06-20 21:21:14.669210 :A761AFAE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8164 osp_idx=23 osp_ver=163025 osp_pg=0 (spawn #305721) 2025-06-20 21:21:14.669211 :A761AFAF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 21:21:14.669212 :A761AFB0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=3430217860 2025-06-20 21:21:14.669212 :A761AFB1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 21:21:14.669213 :A761AFB2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 21:21:14.669213 :A761AFB3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 21:21:14.669214 :A761AFB4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 21:21:14.669214 :A761AFB5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 21:21:14.669807 :A761AFB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 21:21:14.669854 :A761AFBE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 21:31:15.582448 :A7627824:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 21:31:15.899768 :A7627862:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 21:31:15.894 2025-06-20 21:31:15.899770 :A7627863:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17664 pso_num=25 pso_serial#=224 2025-06-20 21:31:15.899910 :A7627864:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17664 osp_idx=23 osp_ver=163026 osp_pg=0 (spawn #305743) 2025-06-20 21:31:15.899911 :A7627865:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 21:31:15.899912 :A7627866:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=3430819094 2025-06-20 21:31:15.899912 :A7627867:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 21:31:15.899913 :A7627868:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 21:31:15.899913 :A7627869:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 21:31:15.899914 :A762786A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 21:31:15.899914 :A762786B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 21:31:15.900418 :A762786C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 21:31:15.900463 :A7627874:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 21:41:16.669510 :A763402E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 21:41:17.132215 :A7634090:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 21:41:17.128 2025-06-20 21:41:17.132217 :A7634091:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14740 pso_num=25 pso_serial#=225 2025-06-20 21:41:17.132362 :A7634092:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14740 osp_idx=23 osp_ver=163027 osp_pg=0 (spawn #305766) 2025-06-20 21:41:17.132363 :A7634093:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 21:41:17.132363 :A7634094:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=3431420329 2025-06-20 21:41:17.132363 :A7634095:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 21:41:17.132364 :A7634096:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 21:41:17.132364 :A7634097:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 21:41:17.132365 :A7634098:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 21:41:17.132365 :A7634099:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 21:41:17.132863 :A763409A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 21:41:17.132909 :A76340A2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 21:51:17.957535 :A764082A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-20 21:51:18.462846 :A7640887:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-20 21:51:18.458 2025-06-20 21:51:18.462848 :A7640888:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14356 pso_num=25 pso_serial#=226 2025-06-20 21:51:18.462983 :A7640889:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14356 osp_idx=23 osp_ver=163028 osp_pg=0 (spawn #305789) 2025-06-20 21:51:18.462983 :A764088A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 21:51:18.462983 :A764088B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=3432021657 2025-06-20 21:51:18.462984 :A764088C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 21:51:18.462984 :A764088D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 21:51:18.462985 :A764088E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 21:51:18.462985 :A764088F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 21:51:18.462985 :A7640890:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 21:51:18.463562 :A7640891:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 21:51:18.463605 :A7640898: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: 14356, W000)