Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250606222131\orcl_m002_15632_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:36857M/63366M, Ph+PgF:43528M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15632, image: ORACLE.EXE (M002) *** 2025-06-06 22:21:31.991 *** SESSION ID:(193.65371) 2025-06-06 22:21:31.991 *** 2025-06-06 22:21:31.991 Process diagnostic dump for ORACLE.EXE (M002), OS id=15632, pid: 24, proc_ser: 26, sid: 193, sess_ser: 65371 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=434 seq_num=435 snap_id=1 wait times: snap=0.003931 sec, exc=0.003931 sec, total=0.003931 sec wait times: max=infinite, heur=0.408676 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000017 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=433 seq_num=434 snap_id=1 wait times: snap=0.004688 sec, exc=0.004688 sec, total=0.004688 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000015 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=432 seq_num=433 snap_id=1 wait times: snap=0.005356 sec, exc=0.005356 sec, total=0.005356 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=431 seq_num=432 snap_id=1 wait times: snap=0.000073 sec, exc=0.000073 sec, total=0.000073 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000014 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=430 seq_num=431 snap_id=1 wait times: snap=0.003390 sec, exc=0.003390 sec, total=0.003390 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=429 seq_num=430 snap_id=1 wait times: snap=0.005750 sec, exc=0.005750 sec, total=0.005750 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=428 seq_num=429 snap_id=1 wait times: snap=0.002184 sec, exc=0.002184 sec, total=0.002184 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=427 seq_num=428 snap_id=1 wait times: snap=0.002568 sec, exc=0.002568 sec, total=0.002568 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=426 seq_num=427 snap_id=1 wait times: snap=0.004982 sec, exc=0.004982 sec, total=0.004982 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000012 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=425 seq_num=426 snap_id=1 wait times: snap=0.001515 sec, exc=0.001515 sec, total=0.001515 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000018 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=424 seq_num=425 snap_id=1 wait times: snap=0.004030 sec, exc=0.004030 sec, total=0.004030 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000016 sec of elapsed time Sampled Session History of session 193 serial 65371 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:21:31] idle wait at each sample [session created at: 22:21:31] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-06 22:21:31.991 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15632, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-06 22:07:37.207302 :A13D195D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13168 pso_num=24 pso_serial#=11 2025-06-06 22:07:37.207433 :A13D195E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13168 osp_idx=22 osp_ver=372585 osp_pg=0 (spawn #259148) 2025-06-06 22:07:37.207434 :A13D195F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:07:37.207434 :A13D1960:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=176 time=2223415844 2025-06-06 22:07:37.207434 :A13D1961:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:07:37.207435 :A13D1962:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:07:37.207435 :A13D1963:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:07:37.207435 :A13D1964:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:07:37.207436 :A13D1965:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:07:37.207909 :A13D1966:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:08:07.307336 :A13D2329:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:08:37.287234 :A13D2D27:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:08:37.276 2025-06-06 22:08:37.287236 :A13D2D28:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16048 pso_num=24 pso_serial#=12 2025-06-06 22:08:37.287373 :A13D2D29:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16048 osp_idx=22 osp_ver=372586 osp_pg=0 (spawn #259151) 2025-06-06 22:08:37.287374 :A13D2D2A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:08:37.287374 :A13D2D2B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=177 time=2223475922 2025-06-06 22:08:37.287375 :A13D2D2C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:08:37.287375 :A13D2D2D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:08:37.287375 :A13D2D2E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:08:37.287376 :A13D2D2F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:08:37.287376 :A13D2D30:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:08:37.287848 :A13D2D31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:09:07.351903 :A13D370D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:09:37.369811 :A13D40EC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:09:37.359 2025-06-06 22:09:37.369813 :A13D40ED:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9764 pso_num=24 pso_serial#=13 2025-06-06 22:09:37.369949 :A13D40EE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9764 osp_idx=22 osp_ver=372587 osp_pg=0 (spawn #259153) 2025-06-06 22:09:37.369949 :A13D40EF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:09:37.369950 :A13D40F0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=178 time=2223536016 2025-06-06 22:09:37.369950 :A13D40F1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:09:37.369951 :A13D40F2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:09:37.369951 :A13D40F3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:09:37.369951 :A13D40F4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:09:37.369952 :A13D40F5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:09:37.370423 :A13D40F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:10:07.446512 :A13D4A4B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:10:37.465752 :A13D57BB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:10:37.454 2025-06-06 22:10:37.465754 :A13D57BC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9628 pso_num=24 pso_serial#=14 2025-06-06 22:10:37.465879 :A13D57BD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9628 osp_idx=22 osp_ver=372588 osp_pg=0 (spawn #259155) 2025-06-06 22:10:37.465880 :A13D57BE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:10:37.465880 :A13D57BF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=179 time=2223596110 2025-06-06 22:10:37.465880 :A13D57C0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:10:37.465881 :A13D57C1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:10:37.465881 :A13D57C2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:10:37.465881 :A13D57C3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:10:37.465881 :A13D57C4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:10:37.466381 :A13D57C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:11:07.537789 :A13D62DB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:11:37.591711 :A13D6CC3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:11:37.580 2025-06-06 22:11:37.591714 :A13D6CC4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15768 pso_num=24 pso_serial#=15 2025-06-06 22:11:37.591869 :A13D6CC5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15768 osp_idx=22 osp_ver=372589 osp_pg=0 (spawn #259158) 2025-06-06 22:11:37.591870 :A13D6CC6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:11:37.591870 :A13D6CC7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=180 time=2223656235 2025-06-06 22:11:37.591871 :A13D6CC8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:11:37.591871 :A13D6CC9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:11:37.591871 :A13D6CCA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:11:37.591872 :A13D6CCB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:11:37.591872 :A13D6CCC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:11:37.592366 :A13D6CCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:12:07.660071 :A13D76C0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:12:12.640887 :A13D7849:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:12:12.629 2025-06-06 22:12:12.640889 :A13D784A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15108 pso_num=24 pso_serial#=16 2025-06-06 22:12:12.641057 :A13D784B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15108 osp_idx=22 osp_ver=372590 osp_pg=0 (spawn #259160) 2025-06-06 22:12:12.641058 :A13D784C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:12:12.641058 :A13D784D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=181 time=2223691282 2025-06-06 22:12:12.641059 :A13D784E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:12:12.641059 :A13D784F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:12:12.641060 :A13D7850:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:12:12.641061 :A13D7851:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:12:12.641061 :A13D7852:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:12:12.641534 :A13D7853:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:12:37.658370 :A13D80B5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 22:13:07.770379 :A13D8A3D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:13:37.774609 :A13D943A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:13:37.763 2025-06-06 22:13:37.774611 :A13D943B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15616 pso_num=24 pso_serial#=17 2025-06-06 22:13:37.774731 :A13D943C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15616 osp_idx=22 osp_ver=372591 osp_pg=0 (spawn #259163) 2025-06-06 22:13:37.774731 :A13D943D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:13:37.774732 :A13D943E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=182 time=2223776422 2025-06-06 22:13:37.774732 :A13D943F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:13:37.774733 :A13D9440:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:13:37.774733 :A13D9441:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:13:37.774733 :A13D9442:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:13:37.774734 :A13D9443:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:13:37.775227 :A13D9444:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:14:07.836924 :A13D9E62:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:14:37.859460 :A13DA84B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:14:37.848 2025-06-06 22:14:37.859462 :A13DA84C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15668 pso_num=24 pso_serial#=18 2025-06-06 22:14:37.859598 :A13DA84D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15668 osp_idx=22 osp_ver=372592 osp_pg=0 (spawn #259165) 2025-06-06 22:14:37.859598 :A13DA84E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:14:37.859599 :A13DA84F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=183 time=2223836500 2025-06-06 22:14:37.859599 :A13DA850:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:14:37.859599 :A13DA851:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:14:37.859600 :A13DA852:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:14:37.859600 :A13DA853:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:14:37.859600 :A13DA854:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:14:37.860079 :A13DA855:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:15:07.955539 :A13DB23A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:15:37.983879 :A13DBC83:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:15:37.972 2025-06-06 22:15:37.983881 :A13DBC84:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12684 pso_num=24 pso_serial#=19 2025-06-06 22:15:37.984019 :A13DBC85:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12684 osp_idx=22 osp_ver=372593 osp_pg=0 (spawn #259167) 2025-06-06 22:15:37.984019 :A13DBC86:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:15:37.984020 :A13DBC87:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=184 time=2223896625 2025-06-06 22:15:37.984020 :A13DBC88:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:15:37.984021 :A13DBC89:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:15:37.984021 :A13DBC8A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:15:37.984021 :A13DBC8B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:15:37.984022 :A13DBC8C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:15:37.984492 :A13DBC8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:16:08.044666 :A13DC69D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:16:38.079635 :A13DD079:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:16:38.068 2025-06-06 22:16:38.079637 :A13DD07A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16080 pso_num=24 pso_serial#=20 2025-06-06 22:16:38.079753 :A13DD07B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16080 osp_idx=22 osp_ver=372594 osp_pg=0 (spawn #259169) 2025-06-06 22:16:38.079753 :A13DD07C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:16:38.079754 :A13DD07D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=185 time=2223956719 2025-06-06 22:16:38.079754 :A13DD07E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:16:38.079754 :A13DD07F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:16:38.079755 :A13DD080:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:16:38.079755 :A13DD081:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:16:38.079755 :A13DD082:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:16:38.080221 :A13DD083:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:17:08.165145 :A13DDA70:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:17:38.142843 :A13DE426:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:17:38.131 2025-06-06 22:17:38.142845 :A13DE427:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10076 pso_num=24 pso_serial#=21 2025-06-06 22:17:38.143024 :A13DE428:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10076 osp_idx=22 osp_ver=372595 osp_pg=0 (spawn #259171) 2025-06-06 22:17:38.143025 :A13DE429:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:17:38.143026 :A13DE42A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=186 time=2224016782 2025-06-06 22:17:38.143026 :A13DE42B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:17:38.143026 :A13DE42C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:17:38.143027 :A13DE42D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:17:38.143028 :A13DE42E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:17:38.143028 :A13DE42F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:17:38.143507 :A13DE430:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:18:08.201602 :A13DEE02:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:18:38.212829 :A13DF7B1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:18:38.201 2025-06-06 22:18:38.212831 :A13DF7B2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16312 pso_num=24 pso_serial#=22 2025-06-06 22:18:38.212957 :A13DF7B3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16312 osp_idx=22 osp_ver=372596 osp_pg=0 (spawn #259173) 2025-06-06 22:18:38.212958 :A13DF7B4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:18:38.212959 :A13DF7B5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=187 time=2224076860 2025-06-06 22:18:38.212959 :A13DF7B6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:18:38.212959 :A13DF7B7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:18:38.212960 :A13DF7B8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:18:38.212960 :A13DF7B9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:18:38.212960 :A13DF7BA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:18:38.213443 :A13DF7BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:19:08.261743 :A13E01BD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:19:38.288268 :A13E0BAA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:19:38.276 2025-06-06 22:19:38.288270 :A13E0BAB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14648 pso_num=24 pso_serial#=23 2025-06-06 22:19:38.288389 :A13E0BAC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14648 osp_idx=22 osp_ver=372597 osp_pg=0 (spawn #259175) 2025-06-06 22:19:38.288390 :A13E0BAD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:19:38.288391 :A13E0BAE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=188 time=2224136922 2025-06-06 22:19:38.288391 :A13E0BAF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:19:38.288391 :A13E0BB0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:19:38.288392 :A13E0BB1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:19:38.288392 :A13E0BB2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:19:38.288392 :A13E0BB3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:19:38.288890 :A13E0BB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:20:08.358766 :A13E1572:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:20:38.346170 :A13E1F52:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:20:38.334 2025-06-06 22:20:38.346172 :A13E1F53:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9580 pso_num=24 pso_serial#=24 2025-06-06 22:20:38.346301 :A13E1F54:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9580 osp_idx=22 osp_ver=372598 osp_pg=0 (spawn #259177) 2025-06-06 22:20:38.346301 :A13E1F55:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:20:38.346302 :A13E1F56:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=189 time=2224196985 2025-06-06 22:20:38.346302 :A13E1F57:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:20:38.346304 :A13E1F58:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:20:38.346304 :A13E1F59:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:20:38.346305 :A13E1F5A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:20:38.346305 :A13E1F5B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:20:38.346841 :A13E1F5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:21:08.442292 :A13E2A4E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:21:30.209531 :A13EA157:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:21:30.200 2025-06-06 22:21:30.209533 :A13EA158:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11564 pso_num=24 pso_serial#=25 2025-06-06 22:21:30.209694 :A13EA159:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11564 osp_idx=22 osp_ver=372599 osp_pg=0 (spawn #259180) 2025-06-06 22:21:30.209694 :A13EA15A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:21:30.209695 :A13EA15B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=190 time=2224248860 2025-06-06 22:21:30.209695 :A13EA15C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:21:30.209695 :A13EA15D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:21:30.209696 :A13EA15E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:21:30.209696 :A13EA15F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:21:30.209696 :A13EA160:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:21:30.211020 :A13EA161:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:21:30.211076 :A13EA167:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 22:21:30.325447 :A13EA1A4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-06 22:21:31.505280 :A13EA1C6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-06 22:21:31.491 2025-06-06 22:21:31.505283 :A13EA1C7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15632 pso_num=24 pso_serial#=26 2025-06-06 22:21:31.505419 :A13EA1C8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15632 osp_idx=22 osp_ver=372600 osp_pg=0 (spawn #259182) 2025-06-06 22:21:31.505420 :A13EA1C9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:21:31.505421 :A13EA1CA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=191 time=2224250141 2025-06-06 22:21:31.505421 :A13EA1CB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:21:31.505422 :A13EA1CC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:21:31.505422 :A13EA1CD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:21:31.505422 :A13EA1CE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:21:31.505423 :A13EA1CF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:21:31.505920 :A13EA1D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:21:31.505972 :A13EA1D6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 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 24 (osid: 15632, M002)