Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250605082045\orcl_j001_16000_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:36922M/63366M, Ph+PgF:43630M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 16000, image: ORACLE.EXE (J001) *** 2025-06-05 08:20:45.394 *** SESSION ID:(193.50211) 2025-06-05 08:20:45.394 *** 2025-06-05 08:20:45.410 Process diagnostic dump for ORACLE.EXE (J001), OS id=16000, pid: 24, proc_ser: 64, sid: 193, sess_ser: 50211 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16000 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=0.296997 sec, exc=0.296997 sec, total=0.296997 sec wait times: max=0.500000 sec, heur=22.319700 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.000014 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 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.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=41 seq_num=42 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.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.510105 sec, exc=0.510105 sec, total=0.510105 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=38 seq_num=39 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.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=37 seq_num=38 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=36 seq_num=37 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.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.499983 sec, exc=0.499983 sec, total=0.499983 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.510116 sec, exc=0.510116 sec, total=0.510116 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time Sampled Session History of session 193 serial 50211 --------------------------------------------------- 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 --------------------------------------------------- [23 samples, 08:20:23 - 08:20:45] idle wait at each sample [session created at: 08:20:23] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-05 08:20:45.410 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 16000, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-05 08:05:21.822376 :A08C0A6A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14208 pso_num=24 pso_serial#=49 2025-06-05 08:05:21.822512 :A08C0A6B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14208 osp_idx=22 osp_ver=365993 osp_pg=0 (spawn #253886) 2025-06-05 08:05:21.822513 :A08C0A6C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:05:21.822513 :A08C0A6D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=177 time=2086480469 2025-06-05 08:05:21.822513 :A08C0A6E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:05:21.822514 :A08C0A6F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:05:21.822514 :A08C0A70:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:05:21.822515 :A08C0A71:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:05:21.822515 :A08C0A72:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:05:21.823064 :A08C0A73: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-06-05 08:05:51.918369 :A08C1AC7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:06:21.888566 :A08C247D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:06:21.877 2025-06-05 08:06:21.888568 :A08C247E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15960 pso_num=24 pso_serial#=50 2025-06-05 08:06:21.888697 :A08C247F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15960 osp_idx=22 osp_ver=365994 osp_pg=0 (spawn #253888) 2025-06-05 08:06:21.888698 :A08C2480:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:06:21.888698 :A08C2481:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=178 time=2086540532 2025-06-05 08:06:21.888699 :A08C2482:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:06:21.888699 :A08C2483:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:06:21.888699 :A08C2484:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:06:21.888700 :A08C2485:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:06:21.888700 :A08C2486:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:06:21.889183 :A08C2487: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-06-05 08:06:51.980908 :A08C2E42:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:07:21.988221 :A08C37CD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:07:21.977 2025-06-05 08:07:21.988224 :A08C37CE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15768 pso_num=24 pso_serial#=51 2025-06-05 08:07:21.988356 :A08C37CF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15768 osp_idx=22 osp_ver=365995 osp_pg=0 (spawn #253891) 2025-06-05 08:07:21.988357 :A08C37D0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:07:21.988357 :A08C37D1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=179 time=2086600625 2025-06-05 08:07:21.988357 :A08C37D2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:07:21.988358 :A08C37D3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:07:21.988358 :A08C37D4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:07:21.988358 :A08C37D5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:07:21.988358 :A08C37D6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:07:21.988815 :A08C37D7: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-06-05 08:07:52.068309 :A08C41CF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:08:22.071673 :A08C4BAB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:08:22.060 2025-06-05 08:08:22.071675 :A08C4BAC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11928 pso_num=24 pso_serial#=52 2025-06-05 08:08:22.071815 :A08C4BAD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11928 osp_idx=22 osp_ver=365996 osp_pg=0 (spawn #253893) 2025-06-05 08:08:22.071816 :A08C4BAE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:08:22.071816 :A08C4BAF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=180 time=2086660719 2025-06-05 08:08:22.071816 :A08C4BB0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:08:22.071817 :A08C4BB1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:08:22.071817 :A08C4BB2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:08:22.071818 :A08C4BB3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:08:22.071818 :A08C4BB4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:08:22.072311 :A08C4BB5: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-06-05 08:08:52.154921 :A08C559F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:08:57.118462 :A08C5738:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:08:57.107 2025-06-05 08:08:57.118464 :A08C5739:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16352 pso_num=24 pso_serial#=53 2025-06-05 08:08:57.118593 :A08C573A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16352 osp_idx=22 osp_ver=365997 osp_pg=0 (spawn #253896) 2025-06-05 08:08:57.118594 :A08C573B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:08:57.118594 :A08C573C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=181 time=2086695766 2025-06-05 08:08:57.118594 :A08C573D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:08:57.118595 :A08C573E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:08:57.118595 :A08C573F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:08:57.118595 :A08C5740:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:08:57.118596 :A08C5741:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:08:57.119112 :A08C5742: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-06-05 08:09:22.131775 :A08C623C: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-06-05 08:09:52.229433 :A08C6C34:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:10:22.222372 :A08C75F8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:10:22.211 2025-06-05 08:10:22.222375 :A08C75F9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15888 pso_num=24 pso_serial#=54 2025-06-05 08:10:22.222509 :A08C75FA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15888 osp_idx=22 osp_ver=365998 osp_pg=0 (spawn #253898) 2025-06-05 08:10:22.222510 :A08C75FB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:10:22.222511 :A08C75FC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=182 time=2086780860 2025-06-05 08:10:22.222511 :A08C75FD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:10:22.222511 :A08C75FE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:10:22.222512 :A08C75FF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:10:22.222512 :A08C7600:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:10:22.222512 :A08C7601:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:10:22.223071 :A08C7602: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-06-05 08:10:52.350203 :A08C7FE8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:11:22.312012 :A08C8982:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:11:22.300 2025-06-05 08:11:22.312014 :A08C8983:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13416 pso_num=24 pso_serial#=55 2025-06-05 08:11:22.312156 :A08C8984:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13416 osp_idx=22 osp_ver=365999 osp_pg=0 (spawn #253900) 2025-06-05 08:11:22.312156 :A08C8985:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:11:22.312157 :A08C8986:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=183 time=2086840954 2025-06-05 08:11:22.312157 :A08C8987:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:11:22.312157 :A08C8988:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:11:22.312158 :A08C8989:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:11:22.312158 :A08C898A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:11:22.312158 :A08C898B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:11:22.312646 :A08C898C: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-06-05 08:11:52.420830 :A08C934A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:12:22.399598 :A08C9D09:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:12:22.389 2025-06-05 08:12:22.399600 :A08C9D0A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2472 pso_num=24 pso_serial#=56 2025-06-05 08:12:22.399735 :A08C9D0B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=2472 osp_idx=22 osp_ver=366000 osp_pg=0 (spawn #253902) 2025-06-05 08:12:22.399736 :A08C9D0C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:12:22.399736 :A08C9D0D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=184 time=2086901047 2025-06-05 08:12:22.399736 :A08C9D0E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:12:22.399737 :A08C9D0F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:12:22.399737 :A08C9D10:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:12:22.399738 :A08C9D11:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:12:22.399738 :A08C9D12:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:12:22.400227 :A08C9D13: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-06-05 08:12:52.466287 :A08CA6A8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:13:22.482577 :A08CB06A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:13:22.471 2025-06-05 08:13:22.482580 :A08CB06B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11372 pso_num=24 pso_serial#=57 2025-06-05 08:13:22.482698 :A08CB06C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11372 osp_idx=22 osp_ver=366001 osp_pg=0 (spawn #253904) 2025-06-05 08:13:22.482699 :A08CB06D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:13:22.482699 :A08CB06E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=185 time=2086961125 2025-06-05 08:13:22.482700 :A08CB06F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:13:22.482700 :A08CB070:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:13:22.482700 :A08CB071:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:13:22.482701 :A08CB072:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:13:22.482701 :A08CB073:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:13:22.483185 :A08CB074: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-06-05 08:13:52.529024 :A08CBA37:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:14:22.550317 :A08CC422:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:14:22.539 2025-06-05 08:14:22.550319 :A08CC423:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16080 pso_num=24 pso_serial#=58 2025-06-05 08:14:22.550455 :A08CC424:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16080 osp_idx=22 osp_ver=366002 osp_pg=0 (spawn #253906) 2025-06-05 08:14:22.550456 :A08CC425:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:14:22.550456 :A08CC426:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=186 time=2087021188 2025-06-05 08:14:22.550456 :A08CC427:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:14:22.550457 :A08CC428:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:14:22.550457 :A08CC429:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:14:22.550458 :A08CC42A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:14:22.550458 :A08CC42B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:14:22.550936 :A08CC42C: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-06-05 08:14:52.621420 :A08CCF18:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:15:22.651450 :A08CD8BF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:15:22.640 2025-06-05 08:15:22.651452 :A08CD8C0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12612 pso_num=24 pso_serial#=59 2025-06-05 08:15:22.651595 :A08CD8C1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12612 osp_idx=22 osp_ver=366003 osp_pg=0 (spawn #253908) 2025-06-05 08:15:22.651596 :A08CD8C2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:15:22.651597 :A08CD8C3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=187 time=2087081297 2025-06-05 08:15:22.651597 :A08CD8C4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:15:22.651597 :A08CD8C5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:15:22.651598 :A08CD8C6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:15:22.651598 :A08CD8C7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:15:22.651598 :A08CD8C8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:15:22.652129 :A08CD8C9: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-06-05 08:15:52.753048 :A08CE28F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:16:22.737523 :A08CEC5A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:16:22.727 2025-06-05 08:16:22.737524 :A08CEC5B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15512 pso_num=24 pso_serial#=60 2025-06-05 08:16:22.737668 :A08CEC5C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15512 osp_idx=22 osp_ver=366004 osp_pg=0 (spawn #253910) 2025-06-05 08:16:22.737668 :A08CEC5D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:16:22.737669 :A08CEC5E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=188 time=2087141375 2025-06-05 08:16:22.737669 :A08CEC5F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:16:22.737670 :A08CEC60:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:16:22.737670 :A08CEC61:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:16:22.737670 :A08CEC62:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:16:22.737671 :A08CEC63:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:16:22.738159 :A08CEC64: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-06-05 08:16:52.804153 :A08CF645:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:17:22.819757 :A08D001A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:17:22.808 2025-06-05 08:17:22.819760 :A08D001B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14744 pso_num=24 pso_serial#=61 2025-06-05 08:17:22.819881 :A08D001C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14744 osp_idx=22 osp_ver=366005 osp_pg=0 (spawn #253913) 2025-06-05 08:17:22.819881 :A08D001D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:17:22.819882 :A08D001E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=189 time=2087201454 2025-06-05 08:17:22.819882 :A08D001F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:17:22.819883 :A08D0020:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:17:22.819883 :A08D0021:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:17:22.819883 :A08D0022:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:17:22.819885 :A08D0023:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:17:22.820390 :A08D0024: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-06-05 08:17:52.861294 :A08D0A2F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:18:22.893809 :A08D13C7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:18:22.883 2025-06-05 08:18:22.893811 :A08D13C8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14520 pso_num=24 pso_serial#=62 2025-06-05 08:18:22.893956 :A08D13C9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14520 osp_idx=22 osp_ver=366006 osp_pg=0 (spawn #253915) 2025-06-05 08:18:22.893957 :A08D13CA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:18:22.893957 :A08D13CB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=190 time=2087261532 2025-06-05 08:18:22.893958 :A08D13CC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:18:22.893958 :A08D13CD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:18:22.893958 :A08D13CE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:18:22.893959 :A08D13CF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:18:22.893959 :A08D13D0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:18:22.894474 :A08D13D1: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-06-05 08:18:52.973292 :A08D1E10:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:18:57.967956 :A08D1FF3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:18:57.957 2025-06-05 08:18:57.967958 :A08D1FF4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15400 pso_num=24 pso_serial#=63 2025-06-05 08:18:57.968093 :A08D1FF5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15400 osp_idx=22 osp_ver=366007 osp_pg=0 (spawn #253919) 2025-06-05 08:18:57.968093 :A08D1FF6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:18:57.968094 :A08D1FF7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=191 time=2087296610 2025-06-05 08:18:57.968094 :A08D1FF8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:18:57.968094 :A08D1FF9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:18:57.968095 :A08D1FFA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:18:57.968095 :A08D1FFB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:18:57.968095 :A08D1FFC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:18:57.968574 :A08D1FFD: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-06-05 08:19:23.005014 :A08D2828: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-06-05 08:19:53.107807 :A08D3274:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 08:20:23.089881 :A08D3C6F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 08:20:23.078 2025-06-05 08:20:23.089883 :A08D3C70:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16000 pso_num=24 pso_serial#=64 2025-06-05 08:20:23.090011 :A08D3C71:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16000 osp_idx=22 osp_ver=366008 osp_pg=0 (spawn #253921) 2025-06-05 08:20:23.090012 :A08D3C72:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-05 08:20:23.090012 :A08D3C73:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=192 time=2087381735 2025-06-05 08:20:23.090013 :A08D3C74:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:20:23.090013 :A08D3C75:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:20:23.090014 :A08D3C76:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:20:23.090014 :A08D3C77:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:20:23.090015 :A08D3C78:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:20:23.090503 :A08D3C79: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: 16000, J001)