Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511082603\orcl_j001_7672_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:37480M/63366M, Ph+PgF:44816M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 7672, image: ORACLE.EXE (J001) *** 2025-05-11 08:26:03.160 *** SESSION ID:(209.36937) 2025-05-11 08:26:03.160 *** 2025-05-11 08:26:03.160 Process diagnostic dump for ORACLE.EXE (J001), OS id=7672, pid: 26, proc_ser: 223, sid: 209, sess_ser: 36937 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 7672 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=0.384970 sec, exc=0.384970 sec, total=0.384970 sec wait times: max=0.500000 sec, heur=20.944726 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=40 seq_num=41 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 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=38 seq_num=39 snap_id=1 wait times: snap=0.499982 sec, exc=0.499982 sec, total=0.499982 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.510094 sec, exc=0.510094 sec, total=0.510094 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=36 seq_num=37 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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=35 seq_num=36 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 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.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=33 seq_num=34 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.503634 sec, exc=0.503634 sec, total=0.503634 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time Sampled Session History of session 209 serial 36937 --------------------------------------------------- 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 --------------------------------------------------- [21 samples, 08:25:42 - 08:26:03] idle wait at each sample [session created at: 08:25:42] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-11 08:26:03.160 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 7672, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-11 08:10:40.790349 :959282B5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11936 pso_num=26 pso_serial#=208 2025-05-11 08:10:40.790479 :959282B6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11936 osp_idx=24 osp_ver=246792 osp_pg=0 (spawn #170514) 2025-05-11 08:10:40.790479 :959282B7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:10:40.790480 :959282B8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=33 time=4221817953 2025-05-11 08:10:40.790480 :959282B9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:10:40.790480 :959282BA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:10:40.790481 :959282BB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:10:40.790481 :959282BC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:10:40.790481 :959282BD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:10:40.790950 :959282BE: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-05-11 08:11:10.847900 :95928C73:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:11:35.861487 :95929467:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:11:35.850 2025-05-11 08:11:35.861489 :95929468:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11128 pso_num=26 pso_serial#=209 2025-05-11 08:11:35.861620 :95929469:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11128 osp_idx=24 osp_ver=246793 osp_pg=0 (spawn #170516) 2025-05-11 08:11:35.861621 :9592946A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:11:35.861621 :9592946B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=34 time=4221873015 2025-05-11 08:11:35.861621 :9592946C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:11:35.861622 :9592946D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:11:35.861622 :9592946E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:11:35.861622 :9592946F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:11:35.861623 :95929470:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:11:35.862104 :95929471: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-05-11 08:11:40.867108 :95929640: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-05-11 08:12:10.967272 :9592A026:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:12:40.971758 :9592A9AC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:12:40.961 2025-05-11 08:12:40.971761 :9592A9AD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8164 pso_num=26 pso_serial#=210 2025-05-11 08:12:40.971890 :9592A9AE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8164 osp_idx=24 osp_ver=246794 osp_pg=0 (spawn #170518) 2025-05-11 08:12:40.971891 :9592A9AF:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:12:40.971891 :9592A9B0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=35 time=4221938125 2025-05-11 08:12:40.971892 :9592A9B1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:12:40.971892 :9592A9B2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:12:40.971893 :9592A9B3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:12:40.971893 :9592A9B4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:12:40.971894 :9592A9B5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:12:40.972363 :9592A9B6: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-05-11 08:13:11.081400 :9592B367:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:13:41.082416 :9592BD14:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:13:41.071 2025-05-11 08:13:41.082418 :9592BD15:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9960 pso_num=26 pso_serial#=211 2025-05-11 08:13:41.082534 :9592BD16:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9960 osp_idx=24 osp_ver=246795 osp_pg=0 (spawn #170521) 2025-05-11 08:13:41.082534 :9592BD17:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:13:41.082535 :9592BD18:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=36 time=4221998250 2025-05-11 08:13:41.082535 :9592BD19:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:13:41.082535 :9592BD1A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:13:41.082536 :9592BD1B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:13:41.082536 :9592BD1C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:13:41.082536 :9592BD1D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:13:41.083023 :9592BD1E: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-05-11 08:14:11.165416 :9592C734:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:14:41.164915 :9592D0BE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:14:41.154 2025-05-11 08:14:41.164917 :9592D0BF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12068 pso_num=26 pso_serial#=212 2025-05-11 08:14:41.165047 :9592D0C0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12068 osp_idx=24 osp_ver=246796 osp_pg=0 (spawn #170523) 2025-05-11 08:14:41.165047 :9592D0C1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:14:41.165048 :9592D0C2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=37 time=4222058328 2025-05-11 08:14:41.165049 :9592D0C3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:14:41.165049 :9592D0C4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:14:41.165049 :9592D0C5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:14:41.165049 :9592D0C6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:14:41.165049 :9592D0C7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:14:41.165577 :9592D0C8: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-05-11 08:15:11.237173 :9592DA62:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:15:41.264380 :9592E3C3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:15:41.253 2025-05-11 08:15:41.264382 :9592E3C4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11688 pso_num=26 pso_serial#=213 2025-05-11 08:15:41.264518 :9592E3C5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11688 osp_idx=24 osp_ver=246797 osp_pg=0 (spawn #170525) 2025-05-11 08:15:41.264518 :9592E3C6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:15:41.264519 :9592E3C7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=38 time=4222118421 2025-05-11 08:15:41.264519 :9592E3C8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:15:41.264519 :9592E3C9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:15:41.264520 :9592E3CA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:15:41.264520 :9592E3CB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:15:41.264521 :9592E3CC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:15:41.264999 :9592E3CD: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-05-11 08:16:11.387788 :9592ED54:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:16:41.397469 :9592F6F5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:16:41.387 2025-05-11 08:16:41.397471 :9592F6F6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10904 pso_num=26 pso_serial#=214 2025-05-11 08:16:41.397606 :9592F6F7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10904 osp_idx=24 osp_ver=246798 osp_pg=0 (spawn #170528) 2025-05-11 08:16:41.397606 :9592F6F8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:16:41.397607 :9592F6F9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=39 time=4222178562 2025-05-11 08:16:41.397607 :9592F6FA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:16:41.397608 :9592F6FB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:16:41.397608 :9592F6FC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:16:41.397609 :9592F6FD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:16:41.397609 :9592F6FE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:16:41.398102 :9592F6FF: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-05-11 08:17:11.495787 :959300D0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:17:41.515770 :95930A6D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:17:41.504 2025-05-11 08:17:41.515772 :95930A6E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12684 pso_num=26 pso_serial#=215 2025-05-11 08:17:41.515887 :95930A6F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12684 osp_idx=24 osp_ver=246799 osp_pg=0 (spawn #170530) 2025-05-11 08:17:41.515888 :95930A70:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:17:41.515888 :95930A71:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=40 time=4222238671 2025-05-11 08:17:41.515888 :95930A72:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:17:41.515889 :95930A73:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:17:41.515889 :95930A74:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:17:41.515890 :95930A75:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:17:41.515890 :95930A76:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:17:41.516359 :95930A77: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-05-11 08:18:11.587168 :9593141B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:18:41.625400 :95931DB6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:18:41.614 2025-05-11 08:18:41.625402 :95931DB7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12656 pso_num=26 pso_serial#=216 2025-05-11 08:18:41.625528 :95931DB8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12656 osp_idx=24 osp_ver=246800 osp_pg=0 (spawn #170532) 2025-05-11 08:18:41.625529 :95931DB9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:18:41.625530 :95931DBA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=41 time=4222298781 2025-05-11 08:18:41.625530 :95931DBB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:18:41.625530 :95931DBC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:18:41.625531 :95931DBD:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:18:41.625531 :95931DBE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:18:41.625531 :95931DBF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:18:41.626039 :95931DC0: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-05-11 08:19:11.678927 :95932843:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:19:41.693200 :959331F9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:19:41.681 2025-05-11 08:19:41.693203 :959331FA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10388 pso_num=26 pso_serial#=217 2025-05-11 08:19:41.693336 :959331FB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10388 osp_idx=24 osp_ver=246801 osp_pg=0 (spawn #170534) 2025-05-11 08:19:41.693337 :959331FC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:19:41.693337 :959331FD:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=42 time=4222358859 2025-05-11 08:19:41.693337 :959331FE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:19:41.693338 :959331FF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:19:41.693338 :95933200:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:19:41.693338 :95933201:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:19:41.693339 :95933202:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:19:41.693805 :95933203: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-05-11 08:20:11.793015 :95933BC3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:20:41.768748 :9593453F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:20:41.758 2025-05-11 08:20:41.768750 :95934540:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9836 pso_num=26 pso_serial#=218 2025-05-11 08:20:41.768878 :95934541:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9836 osp_idx=24 osp_ver=246802 osp_pg=0 (spawn #170536) 2025-05-11 08:20:41.768878 :95934542:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:20:41.768879 :95934543:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=43 time=4222418921 2025-05-11 08:20:41.768879 :95934544:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:20:41.768880 :95934545:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:20:41.768880 :95934546:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:20:41.768881 :95934547:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:20:41.768881 :95934548:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:20:41.769344 :95934549: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-05-11 08:21:11.873356 :95934EE5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:21:36.856991 :959356AA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:21:36.846 2025-05-11 08:21:36.856993 :959356AB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4696 pso_num=26 pso_serial#=219 2025-05-11 08:21:36.857131 :959356AC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=4696 osp_idx=24 osp_ver=246803 osp_pg=0 (spawn #170538) 2025-05-11 08:21:36.857131 :959356AD:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:21:36.857132 :959356AE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=44 time=4222474015 2025-05-11 08:21:36.857132 :959356AF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:21:36.857132 :959356B0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:21:36.857133 :959356B1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:21:36.857133 :959356B2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:21:36.857134 :959356B3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:21:36.857626 :959356B4: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-05-11 08:21:41.848313 :9593589A: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-05-11 08:22:11.933892 :9593623B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:22:41.954308 :95936BD8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:22:41.943 2025-05-11 08:22:41.954310 :95936BD9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10360 pso_num=26 pso_serial#=220 2025-05-11 08:22:41.954433 :95936BDA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10360 osp_idx=24 osp_ver=246804 osp_pg=0 (spawn #170540) 2025-05-11 08:22:41.954434 :95936BDB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:22:41.954434 :95936BDC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=45 time=4222539109 2025-05-11 08:22:41.954434 :95936BDD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:22:41.954434 :95936BDE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:22:41.954435 :95936BDF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:22:41.954435 :95936BE0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:22:41.954435 :95936BE1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:22:41.954894 :95936BE2: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-05-11 08:23:12.033339 :959375B5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:23:42.045930 :95937F76:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:23:42.034 2025-05-11 08:23:42.045932 :95937F77:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8276 pso_num=26 pso_serial#=221 2025-05-11 08:23:42.046059 :95937F78:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8276 osp_idx=24 osp_ver=246805 osp_pg=0 (spawn #170544) 2025-05-11 08:23:42.046059 :95937F79:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:23:42.046060 :95937F7A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=46 time=4222599203 2025-05-11 08:23:42.046060 :95937F7B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:23:42.046060 :95937F7C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:23:42.046061 :95937F7D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:23:42.046061 :95937F7E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:23:42.046061 :95937F7F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:23:42.046573 :95937F80: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-05-11 08:24:12.158186 :95938983:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:24:42.131593 :959392E2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:24:42.121 2025-05-11 08:24:42.131595 :959392E3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3352 pso_num=26 pso_serial#=222 2025-05-11 08:24:42.131727 :959392E4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=3352 osp_idx=24 osp_ver=246806 osp_pg=0 (spawn #170546) 2025-05-11 08:24:42.131727 :959392E5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:24:42.131728 :959392E6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=47 time=4222659296 2025-05-11 08:24:42.131728 :959392E7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:24:42.131728 :959392E8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:24:42.131729 :959392E9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:24:42.131729 :959392EA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:24:42.131729 :959392EB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:24:42.132201 :959392EC: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-05-11 08:25:12.219783 :95939C6B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 08:25:42.224265 :9593A5FB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 08:25:42.213 2025-05-11 08:25:42.224267 :9593A5FC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7672 pso_num=26 pso_serial#=223 2025-05-11 08:25:42.224403 :9593A5FD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7672 osp_idx=24 osp_ver=246807 osp_pg=0 (spawn #170548) 2025-05-11 08:25:42.224404 :9593A5FE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:25:42.224404 :9593A5FF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=48 time=4222719390 2025-05-11 08:25:42.224405 :9593A600:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:25:42.224406 :9593A601:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:25:42.224406 :9593A602:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:25:42.224407 :9593A603:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:25:42.224407 :9593A604:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:25:42.224891 :9593A605: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: 7672, J001)