Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621082903\orcl_j001_12772_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:36421M/63366M, Ph+PgF:42776M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 12772, image: ORACLE.EXE (J001) *** 2025-06-21 08:29:03.745 *** SESSION ID:(209.57011) 2025-06-21 08:29:03.745 *** 2025-06-21 08:29:03.745 Process diagnostic dump for ORACLE.EXE (J001), OS id=12772, pid: 26, proc_ser: 239, sid: 209, sess_ser: 57011 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12772 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.054808 sec, exc=0.054808 sec, total=0.054808 sec wait times: max=0.500000 sec, heur=26.136074 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=51 seq_num=52 snap_id=1 wait times: snap=0.515569 sec, exc=0.515569 sec, total=0.515569 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000039 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.499958 sec, exc=0.499958 sec, total=0.499958 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000075 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 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.000016 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 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.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.499966 sec, exc=0.499966 sec, total=0.499966 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=0.500015 sec, exc=0.500015 sec, total=0.500015 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=0.510084 sec, exc=0.510084 sec, total=0.510084 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time Sampled Session History of session 209 serial 57011 --------------------------------------------------- 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 --------------------------------------------------- [27 samples, 08:28:37 - 08:29:03] idle wait at each sample [session created at: 08:28:37] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-21 08:29:03.745 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 12772, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-21 08:13:06.357966 :A79537A9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:13:36.298081 :A7954181:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:13:36.287 2025-06-21 08:13:36.298083 :A7954182:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14744 pso_num=26 pso_serial#=224 2025-06-21 08:13:36.298200 :A7954183:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14744 osp_idx=24 osp_ver=485744 osp_pg=0 (spawn #307242) 2025-06-21 08:13:36.298201 :A7954184:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:13:36.298201 :A7954185:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=117 time=3469359485 2025-06-21 08:13:36.298202 :A7954186:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:13:36.298202 :A7954187:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:13:36.298203 :A7954188:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:13:36.298203 :A7954189:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:13:36.298203 :A795418A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:13:36.298688 :A795418B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:14:06.367231 :A7954BAF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:14:36.388875 :A79555B5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:14:36.378 2025-06-21 08:14:36.388877 :A79555B6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16724 pso_num=26 pso_serial#=225 2025-06-21 08:14:36.389006 :A79555B7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16724 osp_idx=24 osp_ver=485745 osp_pg=0 (spawn #307244) 2025-06-21 08:14:36.389007 :A79555B8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:14:36.389007 :A79555B9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=118 time=3469419579 2025-06-21 08:14:36.389008 :A79555BA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:14:36.389008 :A79555BB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:14:36.389009 :A79555BC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:14:36.389009 :A79555BD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:14:36.389009 :A79555BE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:14:36.389492 :A79555BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:15:06.465962 :A7955F64:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:15:36.520932 :A7956978:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:15:36.518 2025-06-21 08:15:36.520934 :A7956979:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15596 pso_num=26 pso_serial#=226 2025-06-21 08:15:36.521068 :A795697A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15596 osp_idx=24 osp_ver=485746 osp_pg=0 (spawn #307247) 2025-06-21 08:15:36.521068 :A795697B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:15:36.521069 :A795697C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=119 time=3469479704 2025-06-21 08:15:36.521069 :A795697D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:15:36.521070 :A795697E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:15:36.521070 :A795697F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:15:36.521070 :A7956980:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:15:36.521071 :A7956981:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:15:36.521558 :A7956982:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:16:06.599311 :A79573AE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:16:36.586413 :A7957D85:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:16:36.575 2025-06-21 08:16:36.586415 :A7957D86:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17316 pso_num=26 pso_serial#=227 2025-06-21 08:16:36.586551 :A7957D87:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17316 osp_idx=24 osp_ver=485747 osp_pg=0 (spawn #307249) 2025-06-21 08:16:36.586552 :A7957D88:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:16:36.586552 :A7957D89:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=120 time=3469539766 2025-06-21 08:16:36.586553 :A7957D8A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:16:36.586553 :A7957D8B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:16:36.586553 :A7957D8C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:16:36.586554 :A7957D8D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:16:36.586554 :A7957D8E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:16:36.587044 :A7957D8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:17:06.677607 :A7958768:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:17:36.670736 :A7959156:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:17:36.659 2025-06-21 08:17:36.670738 :A7959157:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10376 pso_num=26 pso_serial#=228 2025-06-21 08:17:36.670866 :A7959158:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10376 osp_idx=24 osp_ver=485748 osp_pg=0 (spawn #307251) 2025-06-21 08:17:36.670867 :A7959159:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:17:36.670867 :A795915A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=121 time=3469599860 2025-06-21 08:17:36.670867 :A795915B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:17:36.670868 :A795915C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:17:36.670868 :A795915D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:17:36.670868 :A795915E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:17:36.670871 :A795915F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:17:36.671333 :A7959160:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:18:06.725176 :A7959AEE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:18:36.747287 :A795A4DC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:18:36.736 2025-06-21 08:18:36.747289 :A795A4DD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13328 pso_num=26 pso_serial#=229 2025-06-21 08:18:36.747420 :A795A4DE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13328 osp_idx=24 osp_ver=485749 osp_pg=0 (spawn #307253) 2025-06-21 08:18:36.747421 :A795A4DF:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:18:36.747422 :A795A4E0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=122 time=3469659938 2025-06-21 08:18:36.747422 :A795A4E1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:18:36.747422 :A795A4E2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:18:36.747423 :A795A4E3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:18:36.747423 :A795A4E4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:18:36.747423 :A795A4E5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:18:36.747904 :A795A4E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:19:06.836089 :A795AEEA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:19:36.819900 :A795B8D6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:19:36.807 2025-06-21 08:19:36.819902 :A795B8D7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14012 pso_num=26 pso_serial#=230 2025-06-21 08:19:36.820035 :A795B8D8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14012 osp_idx=24 osp_ver=485750 osp_pg=0 (spawn #307255) 2025-06-21 08:19:36.820036 :A795B8D9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:19:36.820037 :A795B8DA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=123 time=3469720000 2025-06-21 08:19:36.820037 :A795B8DB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:19:36.820038 :A795B8DC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:19:36.820038 :A795B8DD:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:19:36.820038 :A795B8DE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:19:36.820039 :A795B8DF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:19:36.820522 :A795B8E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:20:06.887905 :A795C2C5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:20:36.901996 :A795CCCA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:20:36.891 2025-06-21 08:20:36.901999 :A795CCCB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16644 pso_num=26 pso_serial#=231 2025-06-21 08:20:36.902168 :A795CCCC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16644 osp_idx=24 osp_ver=485751 osp_pg=0 (spawn #307258) 2025-06-21 08:20:36.902169 :A795CCCD:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:20:36.902169 :A795CCCE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=124 time=3469780079 2025-06-21 08:20:36.902170 :A795CCCF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:20:36.902170 :A795CCD0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:20:36.902170 :A795CCD1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:20:36.902171 :A795CCD2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:20:36.902171 :A795CCD3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:20:36.902672 :A795CCD4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:21:07.009453 :A795D758:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:21:37.001874 :A795E156:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:21:36.990 2025-06-21 08:21:37.001875 :A795E157:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17592 pso_num=26 pso_serial#=232 2025-06-21 08:21:37.002008 :A795E158:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17592 osp_idx=24 osp_ver=485752 osp_pg=0 (spawn #307260) 2025-06-21 08:21:37.002009 :A795E159:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:21:37.002010 :A795E15A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=125 time=3469840188 2025-06-21 08:21:37.002010 :A795E15B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:21:37.002011 :A795E15C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:21:37.002011 :A795E15D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:21:37.002011 :A795E15E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:21:37.002012 :A795E15F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:21:37.002521 :A795E160:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:22:02.020882 :A795E9DF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-21 08:22:32.091146 :A795F3B6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:22:37.073812 :A795F585:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:22:37.062 2025-06-21 08:22:37.073814 :A795F586:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16220 pso_num=26 pso_serial#=233 2025-06-21 08:22:37.073965 :A795F587:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16220 osp_idx=24 osp_ver=485753 osp_pg=0 (spawn #307262) 2025-06-21 08:22:37.073965 :A795F588:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:22:37.073966 :A795F589:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=126 time=3469900250 2025-06-21 08:22:37.073966 :A795F58A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:22:37.073967 :A795F58B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:22:37.073967 :A795F58C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:22:37.073967 :A795F58D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:22:37.073967 :A795F58E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:22:37.074454 :A795F58F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:23:07.140286 :A795FF8B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:23:37.169536 :A796094E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:23:37.158 2025-06-21 08:23:37.169538 :A796094F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17496 pso_num=26 pso_serial#=234 2025-06-21 08:23:37.169678 :A7960950:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17496 osp_idx=24 osp_ver=485754 osp_pg=0 (spawn #307265) 2025-06-21 08:23:37.169678 :A7960951:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:23:37.169679 :A7960952:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=127 time=3469960360 2025-06-21 08:23:37.169679 :A7960953:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:23:37.169679 :A7960954:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:23:37.169680 :A7960955:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:23:37.169680 :A7960956:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:23:37.169680 :A7960957:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:23:37.170152 :A7960958:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:24:07.261279 :A796138D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:24:37.258099 :A7961D6C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:24:37.246 2025-06-21 08:24:37.258101 :A7961D6D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16388 pso_num=26 pso_serial#=235 2025-06-21 08:24:37.258235 :A7961D6E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16388 osp_idx=24 osp_ver=485755 osp_pg=0 (spawn #307267) 2025-06-21 08:24:37.258236 :A7961D6F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:24:37.258236 :A7961D70:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=128 time=3470020438 2025-06-21 08:24:37.258237 :A7961D71:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:24:37.258237 :A7961D72:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:24:37.258237 :A7961D73:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:24:37.258238 :A7961D74:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:24:37.258238 :A7961D75:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:24:37.258780 :A7961D76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:25:07.372778 :A7962741:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:25:37.325487 :A7963163:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:25:37.314 2025-06-21 08:25:37.325489 :A7963164:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17920 pso_num=26 pso_serial#=236 2025-06-21 08:25:37.325682 :A7963165:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17920 osp_idx=24 osp_ver=485756 osp_pg=0 (spawn #307270) 2025-06-21 08:25:37.325683 :A7963166:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:25:37.325684 :A7963167:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=129 time=3470080516 2025-06-21 08:25:37.325684 :A7963168:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:25:37.325685 :A7963169:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:25:37.325685 :A796316A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:25:37.325685 :A796316B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:25:37.325686 :A796316C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:25:37.326194 :A796316D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:26:07.408030 :A7963BAF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:26:37.400243 :A79645A1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:26:37.389 2025-06-21 08:26:37.400244 :A79645A2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16452 pso_num=26 pso_serial#=237 2025-06-21 08:26:37.400385 :A79645A3:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16452 osp_idx=24 osp_ver=485757 osp_pg=0 (spawn #307272) 2025-06-21 08:26:37.400385 :A79645A4:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:26:37.400385 :A79645A5:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=130 time=3470140579 2025-06-21 08:26:37.400386 :A79645A6:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:26:37.400386 :A79645A7:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:26:37.400387 :A79645A8:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:26:37.400387 :A79645A9:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:26:37.400387 :A79645AA:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:26:37.400859 :A79645AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:27:07.479420 :A7964FD9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:27:37.510070 :A79659D0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:27:37.499 2025-06-21 08:27:37.510072 :A79659D1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17100 pso_num=26 pso_serial#=238 2025-06-21 08:27:37.510205 :A79659D2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17100 osp_idx=24 osp_ver=485758 osp_pg=0 (spawn #307274) 2025-06-21 08:27:37.510205 :A79659D3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:27:37.510206 :A79659D4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=131 time=3470200688 2025-06-21 08:27:37.510206 :A79659D5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:27:37.510206 :A79659D6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:27:37.510207 :A79659D7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:27:37.510207 :A79659D8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:27:37.510207 :A79659D9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:27:37.510674 :A79659DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:28:07.594338 :A79663BD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-21 08:28:37.615782 :A7966DE8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-21 08:28:37.604 2025-06-21 08:28:37.615785 :A7966DE9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12772 pso_num=26 pso_serial#=239 2025-06-21 08:28:37.615923 :A7966DEA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12772 osp_idx=24 osp_ver=485759 osp_pg=0 (spawn #307276) 2025-06-21 08:28:37.615924 :A7966DEB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:28:37.615924 :A7966DEC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=132 time=3470260797 2025-06-21 08:28:37.615925 :A7966DED:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:28:37.615925 :A7966DEE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:28:37.615926 :A7966DEF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:28:37.615926 :A7966DF0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:28:37.615927 :A7966DF1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:28:37.616450 :A7966DF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] 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 26 (osid: 12772, J001)