Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726222249\orcl_j001_6572_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:36035M/63366M, Ph+PgF:41818M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 6572, image: ORACLE.EXE (J001) *** 2025-07-26 22:22:49.365 *** SESSION ID:(201.14473) 2025-07-26 22:22:49.365 *** 2025-07-26 22:22:49.365 Process diagnostic dump for ORACLE.EXE (J001), OS id=6572, pid: 25, proc_ser: 83, sid: 201, sess_ser: 14473 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 6572 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.210624 sec, exc=0.210624 sec, total=0.210624 sec wait times: max=0.500000 sec, heur=6.214622 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.502412 sec, exc=0.502412 sec, total=0.502412 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=9 seq_num=10 snap_id=1 wait times: snap=0.500020 sec, exc=0.500020 sec, total=0.500020 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500105 sec, exc=0.500105 sec, total=0.500105 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=5 seq_num=6 snap_id=1 wait times: snap=0.500106 sec, exc=0.500106 sec, total=0.500106 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.503353 sec, exc=0.503353 sec, total=0.503353 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 201 serial 14473 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:22:43 - 22:22:49] idle wait at each sample [session created at: 22:22:43] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 22:22:49.365 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 6572, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 22:07:42.036826 :B7642311:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:07:42.025 2025-07-26 22:07:42.036828 :B7642312:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17920 pso_num=25 pso_serial#=68 2025-07-26 22:07:42.036967 :B7642313:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17920 osp_idx=23 osp_ver=214125 osp_pg=0 (spawn #425842) 2025-07-26 22:07:42.036968 :B7642314:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:07:42.036968 :B7642315:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=2248437923 2025-07-26 22:07:42.036969 :B7642316:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:07:42.036969 :B7642317:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:07:42.036969 :B7642318:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:07:42.036970 :B7642319:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:07:42.036970 :B764231A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:07:42.037447 :B764231B: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-07-26 22:08:12.124909 :B7642CD7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:08:42.128150 :B764367D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:08:42.117 2025-07-26 22:08:42.128152 :B764367E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18312 pso_num=25 pso_serial#=69 2025-07-26 22:08:42.128349 :B764367F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18312 osp_idx=23 osp_ver=214126 osp_pg=0 (spawn #425844) 2025-07-26 22:08:42.128350 :B7643680:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:08:42.128350 :B7643681:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=2248498017 2025-07-26 22:08:42.128351 :B7643682:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:08:42.128352 :B7643683:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:08:42.128352 :B7643684:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:08:42.128353 :B7643685:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:08:42.128353 :B7643686:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:08:42.128827 :B7643687: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-07-26 22:09:12.202786 :B7644037:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:09:42.222920 :B76449E1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:09:42.212 2025-07-26 22:09:42.222922 :B76449E2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7532 pso_num=25 pso_serial#=70 2025-07-26 22:09:42.223051 :B76449E3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7532 osp_idx=23 osp_ver=214127 osp_pg=0 (spawn #425846) 2025-07-26 22:09:42.223052 :B76449E4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:09:42.223053 :B76449E5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=2248558111 2025-07-26 22:09:42.223053 :B76449E6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:09:42.223053 :B76449E7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:09:42.223053 :B76449E8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:09:42.223054 :B76449E9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:09:42.223054 :B76449EA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:09:42.223528 :B76449EB: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-07-26 22:10:07.230914 :B764522D: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-07-26 22:10:37.305596 :B7645D81:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:10:42.298757 :B7645F62:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:10:42.294 2025-07-26 22:10:42.298759 :B7645F63:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19168 pso_num=25 pso_serial#=71 2025-07-26 22:10:42.298907 :B7645F64:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19168 osp_idx=23 osp_ver=214128 osp_pg=0 (spawn #425849) 2025-07-26 22:10:42.298907 :B7645F65:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:10:42.298908 :B7645F66:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=2248618173 2025-07-26 22:10:42.298908 :B7645F67:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:10:42.298909 :B7645F68:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:10:42.298909 :B7645F69:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:10:42.298909 :B7645F6A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:10:42.298910 :B7645F6B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:10:42.299397 :B7645F76: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-07-26 22:11:12.400775 :B7646913:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:11:42.369882 :B76473E7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:11:42.359 2025-07-26 22:11:42.369884 :B76473E8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14100 pso_num=25 pso_serial#=72 2025-07-26 22:11:42.370011 :B76473E9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14100 osp_idx=23 osp_ver=214129 osp_pg=0 (spawn #425851) 2025-07-26 22:11:42.370012 :B76473EA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:11:42.370012 :B76473EB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=159 time=2248678251 2025-07-26 22:11:42.370012 :B76473EC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:11:42.370013 :B76473ED:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:11:42.370013 :B76473EE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:11:42.370013 :B76473EF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:11:42.370014 :B76473F0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:11:42.370518 :B76473F1: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-07-26 22:12:12.448664 :B7647E62:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:12:42.435971 :B76487BA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:12:42.425 2025-07-26 22:12:42.435973 :B76487BB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18600 pso_num=25 pso_serial#=73 2025-07-26 22:12:42.436108 :B76487BC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18600 osp_idx=23 osp_ver=214130 osp_pg=0 (spawn #425854) 2025-07-26 22:12:42.436109 :B76487BD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:12:42.436109 :B76487BE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=160 time=2248738329 2025-07-26 22:12:42.436110 :B76487BF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:12:42.436110 :B76487C0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:12:42.436110 :B76487C1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:12:42.436111 :B76487C2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:12:42.436112 :B76487C3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:12:42.436608 :B76487C4: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-07-26 22:13:12.479021 :B7649143:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:13:42.502021 :B7649AF3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:13:42.490 2025-07-26 22:13:42.502023 :B7649AF4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16524 pso_num=25 pso_serial#=74 2025-07-26 22:13:42.502147 :B7649AF5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16524 osp_idx=23 osp_ver=214131 osp_pg=0 (spawn #425856) 2025-07-26 22:13:42.502147 :B7649AF6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:13:42.502148 :B7649AF7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=161 time=2248798392 2025-07-26 22:13:42.502148 :B7649AF8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:13:42.502148 :B7649AF9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:13:42.502149 :B7649AFA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:13:42.502149 :B7649AFB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:13:42.502149 :B7649AFC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:13:42.502633 :B7649AFD: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-07-26 22:14:12.556055 :B764A476:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:14:42.571166 :B764AE24:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:14:42.560 2025-07-26 22:14:42.571168 :B764AE25:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8628 pso_num=25 pso_serial#=75 2025-07-26 22:14:42.571298 :B764AE26:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8628 osp_idx=23 osp_ver=214132 osp_pg=0 (spawn #425858) 2025-07-26 22:14:42.571298 :B764AE27:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:14:42.571299 :B764AE28:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=162 time=2248858454 2025-07-26 22:14:42.571299 :B764AE29:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:14:42.571300 :B764AE2A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:14:42.571300 :B764AE2B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:14:42.571301 :B764AE2C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:14:42.571301 :B764AE2D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:14:42.571810 :B764AE2E: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-07-26 22:15:12.630454 :B764B800:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:15:42.657448 :B764C1C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:15:42.645 2025-07-26 22:15:42.657451 :B764C1C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18388 pso_num=25 pso_serial#=76 2025-07-26 22:15:42.657565 :B764C1C7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18388 osp_idx=23 osp_ver=214133 osp_pg=0 (spawn #425861) 2025-07-26 22:15:42.657566 :B764C1C8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:15:42.657567 :B764C1C9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=163 time=2248918548 2025-07-26 22:15:42.657567 :B764C1CA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:15:42.657567 :B764C1CB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:15:42.657568 :B764C1CC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:15:42.657568 :B764C1CD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:15:42.657568 :B764C1CE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:15:42.658170 :B764C1CF: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-07-26 22:16:12.741782 :B764CB70:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:16:42.743860 :B764D67C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:16:42.733 2025-07-26 22:16:42.743862 :B764D67D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17424 pso_num=25 pso_serial#=77 2025-07-26 22:16:42.744010 :B764D67E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17424 osp_idx=23 osp_ver=214134 osp_pg=0 (spawn #425863) 2025-07-26 22:16:42.744012 :B764D67F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:16:42.744013 :B764D680:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=2248978626 2025-07-26 22:16:42.744013 :B764D681:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:16:42.744013 :B764D682:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:16:42.744014 :B764D683:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:16:42.744014 :B764D684:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:16:42.744014 :B764D685:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:16:42.744510 :B764D686: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-07-26 22:17:12.814153 :B764E12C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:17:42.827817 :B764EB09:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:17:42.816 2025-07-26 22:17:42.827819 :B764EB0A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15220 pso_num=25 pso_serial#=78 2025-07-26 22:17:42.827943 :B764EB0B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15220 osp_idx=23 osp_ver=214135 osp_pg=0 (spawn #425865) 2025-07-26 22:17:42.827944 :B764EB0C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:17:42.827944 :B764EB0D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=2249038720 2025-07-26 22:17:42.827944 :B764EB0E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:17:42.827945 :B764EB0F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:17:42.827945 :B764EB10:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:17:42.827945 :B764EB11:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:17:42.827946 :B764EB12:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:17:42.828427 :B764EB13: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-07-26 22:18:12.881588 :B764F491:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:18:42.900622 :B764FE1F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:18:42.889 2025-07-26 22:18:42.900625 :B764FE20:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19228 pso_num=25 pso_serial#=79 2025-07-26 22:18:42.900742 :B764FE21:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19228 osp_idx=23 osp_ver=214136 osp_pg=0 (spawn #425867) 2025-07-26 22:18:42.900743 :B764FE22:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:18:42.900743 :B764FE23:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=2249098783 2025-07-26 22:18:42.900743 :B764FE24:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:18:42.900744 :B764FE25:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:18:42.900745 :B764FE26:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:18:42.900745 :B764FE27:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:18:42.900745 :B764FE28:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:18:42.901205 :B764FE29: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-07-26 22:19:12.943649 :B76507B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:19:42.943800 :B765116D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:19:42.932 2025-07-26 22:19:42.943802 :B765116E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9092 pso_num=25 pso_serial#=80 2025-07-26 22:19:42.943930 :B765116F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9092 osp_idx=23 osp_ver=214137 osp_pg=0 (spawn #425869) 2025-07-26 22:19:42.943930 :B7651170:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:19:42.943930 :B7651171:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=167 time=2249158829 2025-07-26 22:19:42.943931 :B7651172:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:19:42.943931 :B7651173:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:19:42.943932 :B7651174:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:19:42.943932 :B7651175:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:19:42.943932 :B7651176:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:19:42.944386 :B7651177: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-07-26 22:20:07.967562 :B76519E8: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-07-26 22:20:38.062756 :B7652390:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:20:43.012517 :B765257E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:20:43.001 2025-07-26 22:20:43.012519 :B765257F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6024 pso_num=25 pso_serial#=81 2025-07-26 22:20:43.012643 :B7652580:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6024 osp_idx=23 osp_ver=214138 osp_pg=0 (spawn #425871) 2025-07-26 22:20:43.012643 :B7652581:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:20:43.012644 :B7652582:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=168 time=2249218908 2025-07-26 22:20:43.012644 :B7652583:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:20:43.012644 :B7652584:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:20:43.012645 :B7652585:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:20:43.012645 :B7652586:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:20:43.012645 :B7652587:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:20:43.013142 :B7652588: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-07-26 22:21:13.064676 :B7652EEE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:21:43.087865 :B7653887:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:21:43.077 2025-07-26 22:21:43.087867 :B7653888:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18628 pso_num=25 pso_serial#=82 2025-07-26 22:21:43.087985 :B7653889:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18628 osp_idx=23 osp_ver=214139 osp_pg=0 (spawn #425873) 2025-07-26 22:21:43.087986 :B765388A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:21:43.087987 :B765388B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=169 time=2249278970 2025-07-26 22:21:43.087987 :B765388C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:21:43.087987 :B765388D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:21:43.087988 :B765388E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:21:43.087988 :B765388F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:21:43.087988 :B7653890:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:21:43.088476 :B7653891: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-07-26 22:22:13.170904 :B76542F7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-26 22:22:43.156426 :B7654C76:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-26 22:22:43.145 2025-07-26 22:22:43.156427 :B7654C77:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6572 pso_num=25 pso_serial#=83 2025-07-26 22:22:43.156543 :B7654C78:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6572 osp_idx=23 osp_ver=214140 osp_pg=0 (spawn #425875) 2025-07-26 22:22:43.156544 :B7654C79:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:22:43.156544 :B7654C7A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=170 time=2249339048 2025-07-26 22:22:43.156544 :B7654C7B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:22:43.156545 :B7654C7C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:22:43.156545 :B7654C7D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:22:43.156545 :B7654C7E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:22:43.156546 :B7654C7F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:22:43.157013 :B7654C80: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: 6572, J001)