Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915082514\orcl_j001_21904_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:34735M/63366M, Ph+PgF:39315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 21904, image: ORACLE.EXE (J001) *** 2025-09-15 08:25:14.244 *** SESSION ID:(201.9827) 2025-09-15 08:25:14.244 *** 2025-09-15 08:25:14.244 Process diagnostic dump for ORACLE.EXE (J001), OS id=21904, pid: 25, proc_ser: 235, sid: 201, sess_ser: 9827 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21904 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.223881 sec, exc=0.223881 sec, total=0.223881 sec wait times: max=0.500000 sec, heur=24.781047 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=0.510096 sec, exc=0.510096 sec, total=0.510096 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=0.499962 sec, exc=0.499962 sec, total=0.499962 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=0.500027 sec, exc=0.500027 sec, total=0.500027 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 201 serial 9827 --------------------------------------------------- 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 --------------------------------------------------- [25 samples, 08:24:49 - 08:25:14] idle wait at each sample [session created at: 08:24:49] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-15 08:25:14.244 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 21904, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-15 08:09:18.067893 :CDA92BB2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:09:48.066562 :CDA935A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:09:48.055 2025-09-15 08:09:48.066564 :CDA935A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25044 pso_num=25 pso_serial#=220 2025-09-15 08:09:48.066691 :CDA935A6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25044 osp_idx=23 osp_ver=300623 osp_pg=0 (spawn #593925) 2025-09-15 08:09:48.066691 :CDA935A7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:09:48.066692 :CDA935A8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=103 time=2309439362 2025-09-15 08:09:48.066692 :CDA935A9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:09:48.066693 :CDA935AA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:09:48.066693 :CDA935AB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:09:48.066694 :CDA935AC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:09:48.066694 :CDA935AD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:09:48.067181 :CDA935AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:10:18.155018 :CDA93F84:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:10:48.183932 :CDA94964:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:10:48.172 2025-09-15 08:10:48.183934 :CDA94965:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10444 pso_num=25 pso_serial#=221 2025-09-15 08:10:48.184066 :CDA94966:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10444 osp_idx=23 osp_ver=300624 osp_pg=0 (spawn #593927) 2025-09-15 08:10:48.184067 :CDA94967:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:10:48.184068 :CDA94968:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=104 time=2309499487 2025-09-15 08:10:48.184068 :CDA94969:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:10:48.184069 :CDA9496A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:10:48.184069 :CDA9496B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:10:48.184069 :CDA9496C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:10:48.184070 :CDA9496D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:10:48.184539 :CDA9496E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:11:18.268797 :CDA9530A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:11:48.294290 :CDA95D1C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:11:48.282 2025-09-15 08:11:48.294292 :CDA95D1D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21212 pso_num=25 pso_serial#=222 2025-09-15 08:11:48.294412 :CDA95D1E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21212 osp_idx=23 osp_ver=300625 osp_pg=0 (spawn #593930) 2025-09-15 08:11:48.294412 :CDA95D1F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:11:48.294413 :CDA95D20:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=105 time=2309559596 2025-09-15 08:11:48.294413 :CDA95D21:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:11:48.294414 :CDA95D22:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:11:48.294414 :CDA95D23:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:11:48.294414 :CDA95D24:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:11:48.294415 :CDA95D25:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:11:48.294902 :CDA95D26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:12:18.413388 :CDA96786:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:12:48.370608 :CDA9714F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:12:48.359 2025-09-15 08:12:48.370609 :CDA97150:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24308 pso_num=25 pso_serial#=223 2025-09-15 08:12:48.370747 :CDA97151:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24308 osp_idx=23 osp_ver=300626 osp_pg=0 (spawn #593932) 2025-09-15 08:12:48.370748 :CDA97152:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:12:48.370748 :CDA97153:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=106 time=2309619674 2025-09-15 08:12:48.370748 :CDA97154:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:12:48.370749 :CDA97155:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:12:48.370749 :CDA97156:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:12:48.370749 :CDA97157:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:12:48.370750 :CDA97158:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:12:48.371305 :CDA97159:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:13:18.424424 :CDA97DFB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:13:48.465931 :CDA987D7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:13:48.454 2025-09-15 08:13:48.465933 :CDA987D8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23636 pso_num=25 pso_serial#=224 2025-09-15 08:13:48.466071 :CDA987D9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23636 osp_idx=23 osp_ver=300627 osp_pg=0 (spawn #593934) 2025-09-15 08:13:48.466072 :CDA987DA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:13:48.466072 :CDA987DB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=107 time=2309679768 2025-09-15 08:13:48.466073 :CDA987DC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:13:48.466073 :CDA987DD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:13:48.466073 :CDA987DE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:13:48.466073 :CDA987DF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:13:48.466074 :CDA987E0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:13:48.466547 :CDA987E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:14:18.544866 :CDA9923C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:14:48.557763 :CDA99C29:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:14:48.546 2025-09-15 08:14:48.557765 :CDA99C2A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25324 pso_num=25 pso_serial#=225 2025-09-15 08:14:48.557909 :CDA99C2B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25324 osp_idx=23 osp_ver=300628 osp_pg=0 (spawn #593937) 2025-09-15 08:14:48.557910 :CDA99C2C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:14:48.557910 :CDA99C2D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=108 time=2309739862 2025-09-15 08:14:48.557911 :CDA99C2E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:14:48.557911 :CDA99C2F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:14:48.557911 :CDA99C30:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:14:48.557912 :CDA99C31:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:14:48.557912 :CDA99C32:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:14:48.558425 :CDA99C33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:15:18.624829 :CDA9A62D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:15:33.639038 :CDA9AB2A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:15:33.628 2025-09-15 08:15:33.639041 :CDA9AB2B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20600 pso_num=25 pso_serial#=226 2025-09-15 08:15:33.639230 :CDA9AB2C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20600 osp_idx=23 osp_ver=300629 osp_pg=0 (spawn #593939) 2025-09-15 08:15:33.639231 :CDA9AB2D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:15:33.639231 :CDA9AB2E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=109 time=2309784940 2025-09-15 08:15:33.639232 :CDA9AB2F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:15:33.639232 :CDA9AB30:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:15:33.639233 :CDA9AB31:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:15:33.639233 :CDA9AB32:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:15:33.639233 :CDA9AB33:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:15:33.639721 :CDA9AB34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:15:48.645794 :CDA9B022:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-15 08:16:18.752389 :CDA9B9FE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:16:48.730501 :CDA9C38E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:16:48.719 2025-09-15 08:16:48.730503 :CDA9C38F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24512 pso_num=25 pso_serial#=227 2025-09-15 08:16:48.730630 :CDA9C390:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24512 osp_idx=23 osp_ver=300630 osp_pg=0 (spawn #593941) 2025-09-15 08:16:48.730631 :CDA9C391:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:16:48.730631 :CDA9C392:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=110 time=2309860033 2025-09-15 08:16:48.730632 :CDA9C393:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:16:48.730632 :CDA9C394:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:16:48.730632 :CDA9C395:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:16:48.730632 :CDA9C396:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:16:48.730633 :CDA9C397:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:16:48.731153 :CDA9C398:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:17:18.796229 :CDA9CD3D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:17:48.819195 :CDA9D706:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:17:48.808 2025-09-15 08:17:48.819197 :CDA9D707:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24776 pso_num=25 pso_serial#=228 2025-09-15 08:17:48.819335 :CDA9D708:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24776 osp_idx=23 osp_ver=300631 osp_pg=0 (spawn #593943) 2025-09-15 08:17:48.819335 :CDA9D709:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:17:48.819336 :CDA9D70A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=111 time=2309920112 2025-09-15 08:17:48.819336 :CDA9D70B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:17:48.819337 :CDA9D70C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:17:48.819337 :CDA9D70D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:17:48.819337 :CDA9D70E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:17:48.819337 :CDA9D70F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:17:48.819821 :CDA9D710:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:18:18.891032 :CDA9E15B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:18:48.911824 :CDA9EB30:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:18:48.900 2025-09-15 08:18:48.911826 :CDA9EB31:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24812 pso_num=25 pso_serial#=229 2025-09-15 08:18:48.911966 :CDA9EB32:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24812 osp_idx=23 osp_ver=300632 osp_pg=0 (spawn #593945) 2025-09-15 08:18:48.911967 :CDA9EB33:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:18:48.911968 :CDA9EB34:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=112 time=2309980205 2025-09-15 08:18:48.911968 :CDA9EB35:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:18:48.911968 :CDA9EB36:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:18:48.911969 :CDA9EB37:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:18:48.911969 :CDA9EB38:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:18:48.911970 :CDA9EB39:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:18:48.912443 :CDA9EB3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:19:18.968800 :CDA9F657:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:19:49.002327 :CDAA0005:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:19:48.990 2025-09-15 08:19:49.002329 :CDAA0006:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23672 pso_num=25 pso_serial#=230 2025-09-15 08:19:49.002476 :CDAA0007:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23672 osp_idx=23 osp_ver=300633 osp_pg=0 (spawn #593948) 2025-09-15 08:19:49.002477 :CDAA0008:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:19:49.002477 :CDAA0009:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=113 time=2310040299 2025-09-15 08:19:49.002478 :CDAA000A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:19:49.002478 :CDAA000B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:19:49.002479 :CDAA000C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:19:49.002479 :CDAA000D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:19:49.002479 :CDAA000E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:19:49.002976 :CDAA000F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:20:19.113794 :CDAA09AC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:20:49.105580 :CDAA135B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:20:49.097 2025-09-15 08:20:49.105582 :CDAA135C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22256 pso_num=25 pso_serial#=231 2025-09-15 08:20:49.105720 :CDAA135D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22256 osp_idx=23 osp_ver=300634 osp_pg=0 (spawn #593950) 2025-09-15 08:20:49.105720 :CDAA135E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:20:49.105721 :CDAA135F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=114 time=2310100408 2025-09-15 08:20:49.105721 :CDAA1360:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:20:49.105722 :CDAA1361:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:20:49.105722 :CDAA1362:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:20:49.105722 :CDAA1363:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:20:49.105723 :CDAA1364:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:20:49.106253 :CDAA1365:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:21:19.183403 :CDAA1D1B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:21:49.216791 :CDAA26D3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:21:49.206 2025-09-15 08:21:49.216793 :CDAA26D4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24660 pso_num=25 pso_serial#=232 2025-09-15 08:21:49.216924 :CDAA26D5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24660 osp_idx=23 osp_ver=300635 osp_pg=0 (spawn #593952) 2025-09-15 08:21:49.216925 :CDAA26D6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:21:49.216925 :CDAA26D7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=115 time=2310160518 2025-09-15 08:21:49.216926 :CDAA26D8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:21:49.216926 :CDAA26D9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:21:49.216926 :CDAA26DA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:21:49.216927 :CDAA26DB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:21:49.216927 :CDAA26DC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:21:49.217390 :CDAA26DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:22:19.288773 :CDAA30FB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:22:49.310382 :CDAA3AA2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:22:49.299 2025-09-15 08:22:49.310384 :CDAA3AA3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23684 pso_num=25 pso_serial#=233 2025-09-15 08:22:49.310514 :CDAA3AA4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23684 osp_idx=23 osp_ver=300636 osp_pg=0 (spawn #593954) 2025-09-15 08:22:49.310515 :CDAA3AA5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:22:49.310516 :CDAA3AA6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=116 time=2310220612 2025-09-15 08:22:49.310516 :CDAA3AA7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:22:49.310517 :CDAA3AA8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:22:49.310517 :CDAA3AA9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:22:49.310517 :CDAA3AAA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:22:49.310518 :CDAA3AAB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:22:49.311004 :CDAA3AAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:23:19.379774 :CDAA4494:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:23:49.372188 :CDAA4E8D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:23:49.361 2025-09-15 08:23:49.372192 :CDAA4E8E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24900 pso_num=25 pso_serial#=234 2025-09-15 08:23:49.372310 :CDAA4E8F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24900 osp_idx=23 osp_ver=300637 osp_pg=0 (spawn #593956) 2025-09-15 08:23:49.372311 :CDAA4E90:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:23:49.372311 :CDAA4E91:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=117 time=2310280674 2025-09-15 08:23:49.372312 :CDAA4E92:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:23:49.372312 :CDAA4E93:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:23:49.372312 :CDAA4E94:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:23:49.372312 :CDAA4E95:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:23:49.372313 :CDAA4E96:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:23:49.372833 :CDAA4E97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-15 08:24:19.449926 :CDAA58D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-15 08:24:49.466615 :CDAA62BD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-15 08:24:49.456 2025-09-15 08:24:49.466618 :CDAA62BE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21904 pso_num=25 pso_serial#=235 2025-09-15 08:24:49.466739 :CDAA62BF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21904 osp_idx=23 osp_ver=300638 osp_pg=0 (spawn #593958) 2025-09-15 08:24:49.466740 :CDAA62C0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:24:49.466740 :CDAA62C1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=118 time=2310340768 2025-09-15 08:24:49.466741 :CDAA62C2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:24:49.466741 :CDAA62C3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:24:49.466741 :CDAA62C4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:24:49.466742 :CDAA62C5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:24:49.466742 :CDAA62C6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:24:49.467233 :CDAA62C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 21904, J001)