Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250917082641\orcl_j001_24112_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:35971M/63366M, Ph+PgF:40274M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 24112, image: ORACLE.EXE (J001) *** 2025-09-17 08:26:41.497 *** SESSION ID:(201.13901) 2025-09-17 08:26:41.497 *** 2025-09-17 08:26:41.497 Process diagnostic dump for ORACLE.EXE (J001), OS id=24112, pid: 25, proc_ser: 105, sid: 201, sess_ser: 13901 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24112 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=0.255531 sec, exc=0.255531 sec, total=0.255531 sec wait times: max=0.500000 sec, heur=27.346610 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.500147 sec, exc=0.500147 sec, total=0.500147 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 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.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 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 13901 --------------------------------------------------- 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 --------------------------------------------------- [28 samples, 08:26:14 - 08:26:41] idle wait at each sample [session created at: 08:26:14] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-17 08:26:41.497 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 24112, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-17 08:10:42.893098 :CE8D4BE0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:11:12.868079 :CE8D5595:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:11:12.856 2025-09-17 08:11:12.868081 :CE8D5596:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4644 pso_num=25 pso_serial#=90 2025-09-17 08:11:12.868201 :CE8D5597:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4644 osp_idx=23 osp_ver=302533 osp_pg=0 (spawn #600599) 2025-09-17 08:11:12.868202 :CE8D5598: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-17 08:11:12.868203 :CE8D5599:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=22 time=2482324158 2025-09-17 08:11:12.868203 :CE8D559A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:11:12.868204 :CE8D559B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:11:12.868205 :CE8D559C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:11:12.868205 :CE8D559D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:11:12.868205 :CE8D559E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:11:12.868865 :CE8D559F: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-17 08:11:42.962671 :CE8D5F86:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:12:12.926153 :CE8D6939:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:12:12.913 2025-09-17 08:12:12.926155 :CE8D693A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25204 pso_num=25 pso_serial#=91 2025-09-17 08:12:12.926278 :CE8D693B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25204 osp_idx=23 osp_ver=302534 osp_pg=0 (spawn #600601) 2025-09-17 08:12:12.926279 :CE8D693C: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-17 08:12:12.926279 :CE8D693D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=23 time=2482384221 2025-09-17 08:12:12.926279 :CE8D693E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:12:12.926280 :CE8D693F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:12:12.926280 :CE8D6940:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:12:12.926281 :CE8D6941:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:12:12.926281 :CE8D6942:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:12:12.927067 :CE8D6943: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-17 08:12:43.048752 :CE8D72E5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:13:13.005362 :CE8D7F5D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:13:12.993 2025-09-17 08:13:13.005364 :CE8D7F5E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21476 pso_num=25 pso_serial#=92 2025-09-17 08:13:13.005481 :CE8D7F5F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21476 osp_idx=23 osp_ver=302535 osp_pg=0 (spawn #600603) 2025-09-17 08:13:13.005482 :CE8D7F60: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-17 08:13:13.005482 :CE8D7F61:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=24 time=2482444299 2025-09-17 08:13:13.005483 :CE8D7F62:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:13:13.005483 :CE8D7F63:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:13:13.005483 :CE8D7F64:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:13:13.005484 :CE8D7F65:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:13:13.005484 :CE8D7F66:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:13:13.006064 :CE8D7F67: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-17 08:13:43.069318 :CE8D897C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:14:13.074563 :CE8D9315:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:14:13.063 2025-09-17 08:14:13.074565 :CE8D9316:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20616 pso_num=25 pso_serial#=93 2025-09-17 08:14:13.074676 :CE8D9317:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20616 osp_idx=23 osp_ver=302536 osp_pg=0 (spawn #600605) 2025-09-17 08:14:13.074677 :CE8D9318: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-17 08:14:13.074677 :CE8D9319:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=25 time=2482504377 2025-09-17 08:14:13.074677 :CE8D931A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:14:13.074678 :CE8D931B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:14:13.074678 :CE8D931C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:14:13.074678 :CE8D931D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:14:13.074679 :CE8D931E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:14:13.075277 :CE8D931F: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-17 08:14:43.180741 :CE8D9CBD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:15:13.189711 :CE8DA6A2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:15:13.178 2025-09-17 08:15:13.189712 :CE8DA6A3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19388 pso_num=25 pso_serial#=94 2025-09-17 08:15:13.189838 :CE8DA6A4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19388 osp_idx=23 osp_ver=302537 osp_pg=0 (spawn #600607) 2025-09-17 08:15:13.189839 :CE8DA6A5: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-17 08:15:13.189839 :CE8DA6A6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=26 time=2482564487 2025-09-17 08:15:13.189840 :CE8DA6A7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:15:13.189840 :CE8DA6A8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:15:13.189840 :CE8DA6A9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:15:13.189840 :CE8DA6AA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:15:13.189841 :CE8DA6AB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:15:13.190499 :CE8DA6AC: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-17 08:15:43.281518 :CE8DB084:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:16:13.281283 :CE8DBA22:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:16:13.269 2025-09-17 08:16:13.281285 :CE8DBA23:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24708 pso_num=25 pso_serial#=95 2025-09-17 08:16:13.281404 :CE8DBA24:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24708 osp_idx=23 osp_ver=302538 osp_pg=0 (spawn #600610) 2025-09-17 08:16:13.281405 :CE8DBA25: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-17 08:16:13.281405 :CE8DBA26:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=27 time=2482624580 2025-09-17 08:16:13.281406 :CE8DBA27:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:16:13.281406 :CE8DBA28:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:16:13.281407 :CE8DBA29:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:16:13.281407 :CE8DBA2A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:16:13.281407 :CE8DBA2B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:16:13.282025 :CE8DBA2C: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-17 08:16:43.368477 :CE8DC3FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:17:13.363885 :CE8DCDA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:17:13.351 2025-09-17 08:17:13.363887 :CE8DCDAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24812 pso_num=25 pso_serial#=96 2025-09-17 08:17:13.364002 :CE8DCDAB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24812 osp_idx=23 osp_ver=302539 osp_pg=0 (spawn #600612) 2025-09-17 08:17:13.364003 :CE8DCDAC: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-17 08:17:13.364004 :CE8DCDAD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=28 time=2482684658 2025-09-17 08:17:13.364004 :CE8DCDAE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:17:13.364004 :CE8DCDAF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:17:13.364005 :CE8DCDB0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:17:13.364005 :CE8DCDB1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:17:13.364005 :CE8DCDB2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:17:13.364578 :CE8DCDB3: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-17 08:17:43.450922 :CE8DD755:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:18:13.469783 :CE8DE131:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:18:13.457 2025-09-17 08:18:13.469785 :CE8DE132:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25064 pso_num=25 pso_serial#=97 2025-09-17 08:18:13.469968 :CE8DE133:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25064 osp_idx=23 osp_ver=302540 osp_pg=0 (spawn #600615) 2025-09-17 08:18:13.469969 :CE8DE134: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-17 08:18:13.469970 :CE8DE135:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=29 time=2482744768 2025-09-17 08:18:13.469971 :CE8DE136:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:18:13.469972 :CE8DE137:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:18:13.469972 :CE8DE138:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:18:13.469972 :CE8DE139:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:18:13.469973 :CE8DE13A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:18:13.470721 :CE8DE13B: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-17 08:18:43.552839 :CE8DEBEF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:19:13.554845 :CE8DF598:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:19:13.544 2025-09-17 08:19:13.554847 :CE8DF599:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24880 pso_num=25 pso_serial#=98 2025-09-17 08:19:13.554976 :CE8DF59A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24880 osp_idx=23 osp_ver=302541 osp_pg=0 (spawn #600617) 2025-09-17 08:19:13.554977 :CE8DF59B: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-17 08:19:13.554977 :CE8DF59C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=30 time=2482804846 2025-09-17 08:19:13.554977 :CE8DF59D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:19:13.554978 :CE8DF59E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:19:13.554978 :CE8DF59F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:19:13.554979 :CE8DF5A0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:19:13.554979 :CE8DF5A1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:19:13.555523 :CE8DF5A2: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-17 08:19:43.672427 :CE8DFF20:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:19:58.606065 :CE8E0433:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:19:58.594 2025-09-17 08:19:58.606067 :CE8E0434:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23628 pso_num=25 pso_serial#=99 2025-09-17 08:19:58.606196 :CE8E0435:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23628 osp_idx=23 osp_ver=302542 osp_pg=0 (spawn #600619) 2025-09-17 08:19:58.606197 :CE8E0436: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-17 08:19:58.606197 :CE8E0437:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=31 time=2482849908 2025-09-17 08:19:58.606197 :CE8E0438:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:19:58.606198 :CE8E0439:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:19:58.606198 :CE8E043A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:19:58.606198 :CE8E043B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:19:58.606199 :CE8E043C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:19:58.606702 :CE8E043D: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-17 08:20:13.611703 :CE8E08F4: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-17 08:20:43.693689 :CE8E12E0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:21:13.708382 :CE8E1C6D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:21:13.697 2025-09-17 08:21:13.708384 :CE8E1C6E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24264 pso_num=25 pso_serial#=100 2025-09-17 08:21:13.708496 :CE8E1C6F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24264 osp_idx=23 osp_ver=302543 osp_pg=0 (spawn #600622) 2025-09-17 08:21:13.708497 :CE8E1C70: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-17 08:21:13.708497 :CE8E1C71:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=32 time=2482925002 2025-09-17 08:21:13.708497 :CE8E1C72:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:21:13.708498 :CE8E1C73:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:21:13.708498 :CE8E1C74:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:21:13.708498 :CE8E1C75:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:21:13.708499 :CE8E1C76:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:21:13.709074 :CE8E1C77: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-17 08:21:43.762407 :CE8E2690:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:22:13.804890 :CE8E3016:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:22:13.792 2025-09-17 08:22:13.804892 :CE8E3017:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22572 pso_num=25 pso_serial#=101 2025-09-17 08:22:13.805015 :CE8E3018:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22572 osp_idx=23 osp_ver=302544 osp_pg=0 (spawn #600624) 2025-09-17 08:22:13.805016 :CE8E3019: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-17 08:22:13.805016 :CE8E301A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=33 time=2482985096 2025-09-17 08:22:13.805017 :CE8E301B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:22:13.805017 :CE8E301C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:22:13.805017 :CE8E301D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:22:13.805018 :CE8E301E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:22:13.805018 :CE8E301F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:22:13.805630 :CE8E3020: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-17 08:22:43.914384 :CE8E399E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:23:13.899259 :CE8E4349:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:23:13.888 2025-09-17 08:23:13.899261 :CE8E434A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25016 pso_num=25 pso_serial#=102 2025-09-17 08:23:13.899376 :CE8E434B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25016 osp_idx=23 osp_ver=302545 osp_pg=0 (spawn #600626) 2025-09-17 08:23:13.899377 :CE8E434C: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-17 08:23:13.899378 :CE8E434D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=2483045190 2025-09-17 08:23:13.899378 :CE8E434E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:23:13.899379 :CE8E434F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:23:13.899379 :CE8E4350:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:23:13.899379 :CE8E4351:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:23:13.899380 :CE8E4352:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:23:13.899968 :CE8E4353: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-17 08:23:43.995544 :CE8E4D8F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:24:13.982838 :CE8E5727:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:24:13.971 2025-09-17 08:24:13.982841 :CE8E5728:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20996 pso_num=25 pso_serial#=103 2025-09-17 08:24:13.982962 :CE8E5729:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20996 osp_idx=23 osp_ver=302546 osp_pg=0 (spawn #600628) 2025-09-17 08:24:13.982962 :CE8E572A: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-17 08:24:13.982963 :CE8E572B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=2483105283 2025-09-17 08:24:13.982963 :CE8E572C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:24:13.982964 :CE8E572D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:24:13.982964 :CE8E572E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:24:13.982964 :CE8E572F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:24:13.982964 :CE8E5730:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:24:13.983509 :CE8E5731: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-17 08:24:44.056527 :CE8E60A8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:25:14.078412 :CE8E69F0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:25:14.065 2025-09-17 08:25:14.078415 :CE8E69F1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24200 pso_num=25 pso_serial#=104 2025-09-17 08:25:14.078539 :CE8E69F2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24200 osp_idx=23 osp_ver=302547 osp_pg=0 (spawn #600630) 2025-09-17 08:25:14.078539 :CE8E69F3: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-17 08:25:14.078540 :CE8E69F4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=2483165377 2025-09-17 08:25:14.078540 :CE8E69F5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:25:14.078540 :CE8E69F6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:25:14.078541 :CE8E69F7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:25:14.078541 :CE8E69F8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:25:14.078541 :CE8E69F9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:25:14.079264 :CE8E69FA: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-17 08:25:44.158305 :CE8E73B5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-17 08:26:14.155520 :CE8E7D6B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-17 08:26:14.143 2025-09-17 08:26:14.155522 :CE8E7D6C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24112 pso_num=25 pso_serial#=105 2025-09-17 08:26:14.155646 :CE8E7D6D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24112 osp_idx=23 osp_ver=302548 osp_pg=0 (spawn #600633) 2025-09-17 08:26:14.155647 :CE8E7D6E: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-17 08:26:14.155647 :CE8E7D6F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=2483225455 2025-09-17 08:26:14.155648 :CE8E7D70:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 08:26:14.155648 :CE8E7D71:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 08:26:14.155648 :CE8E7D72:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 08:26:14.155649 :CE8E7D73:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 08:26:14.155649 :CE8E7D74:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 08:26:14.156320 :CE8E7D75: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: 24112, J001)