Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250724222159\orcl_j001_14876_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:36104M/63366M, Ph+PgF:41914M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 14876, image: ORACLE.EXE (J001) *** 2025-07-24 22:22:00.079 *** SESSION ID:(193.42383) 2025-07-24 22:22:00.079 *** 2025-07-24 22:22:00.079 Process diagnostic dump for ORACLE.EXE (J001), OS id=14876, pid: 24, proc_ser: 73, sid: 193, sess_ser: 42383 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14876 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.298440 sec, exc=0.298440 sec, total=0.298440 sec wait times: max=0.500000 sec, heur=15.305308 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 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.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=0.515208 sec, exc=0.515208 sec, total=0.515208 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 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=25 seq_num=26 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=0.502085 sec, exc=0.502085 sec, total=0.502085 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time Sampled Session History of session 193 serial 42383 --------------------------------------------------- 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 --------------------------------------------------- [16 samples, 22:21:44 - 22:22:00] idle wait at each sample [session created at: 22:21:44] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-24 22:22:00.079 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 14876, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-24 22:06:38.237795 :B6819E23:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:06:43.243341 :B6819F78:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:06:43.239 2025-07-24 22:06:43.243343 :B6819F79:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17616 pso_num=24 pso_serial#=58 2025-07-24 22:06:43.243472 :B6819F7A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17616 osp_idx=22 osp_ver=614618 osp_pg=0 (spawn #419173) 2025-07-24 22:06:43.243473 :B6819F7B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:06:43.243474 :B6819F7C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=158 time=2075579126 2025-07-24 22:06:43.243474 :B6819F7D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:06:43.243475 :B6819F7E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:06:43.243475 :B6819F7F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:06:43.243476 :B6819F80:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:06:43.243476 :B6819F81:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:06:43.244002 :B6819F82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:07:13.323456 :B681A965:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:07:43.337839 :B681B2E0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:07:43.326 2025-07-24 22:07:43.337841 :B681B2E1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6128 pso_num=24 pso_serial#=59 2025-07-24 22:07:43.337960 :B681B2E2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6128 osp_idx=22 osp_ver=614619 osp_pg=0 (spawn #419175) 2025-07-24 22:07:43.337961 :B681B2E3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:07:43.337961 :B681B2E4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=159 time=2075639220 2025-07-24 22:07:43.337962 :B681B2E5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:07:43.337964 :B681B2E6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:07:43.337964 :B681B2E7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:07:43.337965 :B681B2E8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:07:43.337965 :B681B2E9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:07:43.338544 :B681B2EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:08:13.413896 :B681BCCA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:08:43.435100 :B681C6D8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:08:43.424 2025-07-24 22:08:43.435102 :B681C6D9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19412 pso_num=24 pso_serial#=60 2025-07-24 22:08:43.435239 :B681C6DA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19412 osp_idx=22 osp_ver=614620 osp_pg=0 (spawn #419177) 2025-07-24 22:08:43.435240 :B681C6DB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:08:43.435240 :B681C6DC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=160 time=2075699329 2025-07-24 22:08:43.435240 :B681C6DD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:08:43.435241 :B681C6DE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:08:43.435241 :B681C6DF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:08:43.435242 :B681C6E0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:08:43.435242 :B681C6E1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:08:43.435733 :B681C6E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:09:13.483647 :B681D186:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:09:43.536513 :B681DB9A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:09:43.525 2025-07-24 22:09:43.536515 :B681DB9B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5116 pso_num=24 pso_serial#=61 2025-07-24 22:09:43.536654 :B681DB9C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=5116 osp_idx=22 osp_ver=614621 osp_pg=0 (spawn #419180) 2025-07-24 22:09:43.536655 :B681DB9D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:09:43.536655 :B681DB9E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=161 time=2075759423 2025-07-24 22:09:43.536656 :B681DB9F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:09:43.536656 :B681DBA0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:09:43.536656 :B681DBA1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:09:43.536657 :B681DBA2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:09:43.536657 :B681DBA3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:09:43.537188 :B681DBA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:10:13.619123 :B681E582:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:10:43.671683 :B681EEED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:10:43.660 2025-07-24 22:10:43.671685 :B681EEEE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16852 pso_num=24 pso_serial#=62 2025-07-24 22:10:43.671797 :B681EEEF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16852 osp_idx=22 osp_ver=614622 osp_pg=0 (spawn #419182) 2025-07-24 22:10:43.671798 :B681EEF0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:10:43.671798 :B681EEF1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=162 time=2075819564 2025-07-24 22:10:43.671799 :B681EEF2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:10:43.671800 :B681EEF3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:10:43.671800 :B681EEF4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:10:43.671800 :B681EEF5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:10:43.671801 :B681EEF6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:10:43.672302 :B681EEF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:11:13.783428 :B681F8E7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:11:43.800030 :B6820297:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:11:43.788 2025-07-24 22:11:43.800032 :B6820298:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12980 pso_num=24 pso_serial#=63 2025-07-24 22:11:43.800168 :B6820299:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12980 osp_idx=22 osp_ver=614623 osp_pg=0 (spawn #419184) 2025-07-24 22:11:43.800169 :B682029A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:11:43.800170 :B682029B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=163 time=2075879689 2025-07-24 22:11:43.800170 :B682029C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:11:43.800170 :B682029D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:11:43.800171 :B682029E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:11:43.800171 :B682029F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:11:43.800171 :B68202A0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:11:43.800642 :B68202A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:12:13.872458 :B6820CF8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:12:43.894713 :B68216E4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:12:43.884 2025-07-24 22:12:43.894716 :B68216E5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19168 pso_num=24 pso_serial#=64 2025-07-24 22:12:43.894857 :B68216E6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19168 osp_idx=22 osp_ver=614624 osp_pg=0 (spawn #419186) 2025-07-24 22:12:43.894857 :B68216E7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:12:43.894857 :B68216E8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=164 time=2075939783 2025-07-24 22:12:43.894858 :B68216E9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:12:43.894858 :B68216EA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:12:43.894858 :B68216EB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:12:43.894859 :B68216EC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:12:43.894859 :B68216ED:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:12:43.895380 :B68216EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:13:13.968009 :B6822086:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:13:44.010765 :B6822DFA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:13:43.999 2025-07-24 22:13:44.010767 :B6822DFB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10620 pso_num=24 pso_serial#=65 2025-07-24 22:13:44.010901 :B6822DFC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10620 osp_idx=22 osp_ver=614625 osp_pg=0 (spawn #419188) 2025-07-24 22:13:44.010902 :B6822DFD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:13:44.010902 :B6822DFE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=165 time=2075999892 2025-07-24 22:13:44.010903 :B6822DFF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:13:44.010903 :B6822E00:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:13:44.010904 :B6822E01:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:13:44.010904 :B6822E02:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:13:44.010904 :B6822E03:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:13:44.011427 :B6822E04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:14:14.089498 :B6823852:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:14:44.116035 :B6824239:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:14:44.105 2025-07-24 22:14:44.116037 :B682423A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11644 pso_num=24 pso_serial#=66 2025-07-24 22:14:44.116176 :B682423B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11644 osp_idx=22 osp_ver=614626 osp_pg=0 (spawn #419190) 2025-07-24 22:14:44.116176 :B682423C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:14:44.116177 :B682423D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=166 time=2076060001 2025-07-24 22:14:44.116177 :B682423E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:14:44.116178 :B682423F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:14:44.116178 :B6824240:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:14:44.116178 :B6824241:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:14:44.116179 :B6824242:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:14:44.116665 :B6824243:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:15:14.213205 :B6824C20:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:15:44.237526 :B68255F5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:15:44.226 2025-07-24 22:15:44.237528 :B68255F6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15748 pso_num=24 pso_serial#=67 2025-07-24 22:15:44.237658 :B68255F7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15748 osp_idx=22 osp_ver=614627 osp_pg=0 (spawn #419192) 2025-07-24 22:15:44.237658 :B68255F8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:15:44.237659 :B68255F9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=167 time=2076120126 2025-07-24 22:15:44.237659 :B68255FA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:15:44.237660 :B68255FB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:15:44.237661 :B68255FC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:15:44.237661 :B68255FD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:15:44.237661 :B68255FE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:15:44.238160 :B68255FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:16:09.253039 :B6825E4E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-24 22:16:39.360781 :B682684D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:16:44.314139 :B682698E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:16:44.302 2025-07-24 22:16:44.314142 :B682698F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8336 pso_num=24 pso_serial#=68 2025-07-24 22:16:44.314276 :B6826990:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8336 osp_idx=22 osp_ver=614628 osp_pg=0 (spawn #419195) 2025-07-24 22:16:44.314276 :B6826991:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:16:44.314277 :B6826992:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=168 time=2076180204 2025-07-24 22:16:44.314277 :B6826993:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:16:44.314278 :B6826994:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:16:44.314278 :B6826995:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:16:44.314278 :B6826996:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:16:44.314279 :B6826997:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:16:44.314763 :B6826998:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:17:14.411037 :B68273A8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:17:44.423273 :B6827D6A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:17:44.411 2025-07-24 22:17:44.423275 :B6827D6B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16624 pso_num=24 pso_serial#=69 2025-07-24 22:17:44.423405 :B6827D6C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16624 osp_idx=22 osp_ver=614629 osp_pg=0 (spawn #419197) 2025-07-24 22:17:44.423406 :B6827D6D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:17:44.423406 :B6827D6E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=169 time=2076240314 2025-07-24 22:17:44.423406 :B6827D6F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:17:44.423407 :B6827D70:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:17:44.423407 :B6827D71:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:17:44.423408 :B6827D72:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:17:44.423408 :B6827D73:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:17:44.423878 :B6827D74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:18:14.540939 :B6828749:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:18:44.482888 :B68291AA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:18:44.471 2025-07-24 22:18:44.482890 :B68291AB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19056 pso_num=24 pso_serial#=70 2025-07-24 22:18:44.483008 :B68291AC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19056 osp_idx=22 osp_ver=614630 osp_pg=0 (spawn #419199) 2025-07-24 22:18:44.483009 :B68291AD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:18:44.483009 :B68291AE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=170 time=2076300376 2025-07-24 22:18:44.483009 :B68291AF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:18:44.483010 :B68291B0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:18:44.483010 :B68291B1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:18:44.483010 :B68291B2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:18:44.483011 :B68291B3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:18:44.483487 :B68291B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:19:14.552267 :B6829C01:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:19:44.548371 :B682A633:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:19:44.537 2025-07-24 22:19:44.548373 :B682A634:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16608 pso_num=24 pso_serial#=71 2025-07-24 22:19:44.548508 :B682A635:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16608 osp_idx=22 osp_ver=614631 osp_pg=0 (spawn #419203) 2025-07-24 22:19:44.548508 :B682A636:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:19:44.548509 :B682A637:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=171 time=2076360439 2025-07-24 22:19:44.548509 :B682A638:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:19:44.548510 :B682A639:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:19:44.548510 :B682A63A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:19:44.548510 :B682A63B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:19:44.548510 :B682A63C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:19:44.548991 :B682A63D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:20:14.638426 :B682AFF4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:20:44.657622 :B682B9E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:20:44.646 2025-07-24 22:20:44.657625 :B682B9E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16496 pso_num=24 pso_serial#=72 2025-07-24 22:20:44.657754 :B682B9E3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16496 osp_idx=22 osp_ver=614632 osp_pg=0 (spawn #419205) 2025-07-24 22:20:44.657754 :B682B9E4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:20:44.657755 :B682B9E5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=172 time=2076420548 2025-07-24 22:20:44.657755 :B682B9E6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:20:44.657756 :B682B9E7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:20:44.657756 :B682B9E8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:20:44.657756 :B682B9E9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:20:44.657756 :B682B9EA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:20:44.658228 :B682B9EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:21:14.750532 :B682C3D6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-24 22:21:44.774056 :B682CD93:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-24 22:21:44.763 2025-07-24 22:21:44.774058 :B682CD94:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14876 pso_num=24 pso_serial#=73 2025-07-24 22:21:44.774185 :B682CD95:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14876 osp_idx=22 osp_ver=614633 osp_pg=0 (spawn #419207) 2025-07-24 22:21:44.774185 :B682CD96:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:21:44.774186 :B682CD97:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=173 time=2076480658 2025-07-24 22:21:44.774186 :B682CD98:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:21:44.774187 :B682CD99:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:21:44.774187 :B682CD9A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:21:44.774187 :B682CD9B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:21:44.774187 :B682CD9C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:21:44.774686 :B682CD9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] 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 24 (osid: 14876, J001)