Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524081114\orcl_j001_11720_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:37306M/63366M, Ph+PgF:44327M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 11720, image: ORACLE.EXE (J001) *** 2025-05-24 08:11:14.296 *** SESSION ID:(201.56711) 2025-05-24 08:11:14.296 *** 2025-05-24 08:11:14.296 Process diagnostic dump for ORACLE.EXE (J001), OS id=11720, pid: 25, proc_ser: 166, sid: 201, sess_ser: 56711 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11720 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.486864 sec, exc=0.486864 sec, total=0.486864 sec wait times: max=0.500000 sec, heur=12.525472 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 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=21 seq_num=22 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.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=19 seq_num=20 snap_id=1 wait times: snap=0.510107 sec, exc=0.510107 sec, total=0.510107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=18 seq_num=19 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 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.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000011 sec of elapsed time Sampled Session History of session 201 serial 56711 --------------------------------------------------- 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 --------------------------------------------------- [13 samples, 08:11:01 - 08:11:14] idle wait at each sample [session created at: 08:11:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-24 08:11:14.296 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 11720, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-24 07:55:30.224341 :9B473986:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 07:56:00.248012 :9B474320:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 07:56:00.237 2025-05-24 07:56:00.248014 :9B474321:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10716 pso_num=25 pso_serial#=151 2025-05-24 07:56:00.248182 :9B474322:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10716 osp_idx=23 osp_ver=113625 osp_pg=0 (spawn #213882) 2025-05-24 07:56:00.248183 :9B474323:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:56:00.248183 :9B474324:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=53 time=1049132500 2025-05-24 07:56:00.248183 :9B474325:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:56:00.248184 :9B474326:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:56:00.248184 :9B474327:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:56:00.248185 :9B474328:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:56:00.248185 :9B474329:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:56:00.248706 :9B47432A: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-05-24 07:56:30.354652 :9B474CDB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 07:57:00.334120 :9B4756BE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 07:57:00.322 2025-05-24 07:57:00.334122 :9B4756BF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1112 pso_num=25 pso_serial#=152 2025-05-24 07:57:00.334258 :9B4756C0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1112 osp_idx=23 osp_ver=113626 osp_pg=0 (spawn #213884) 2025-05-24 07:57:00.334258 :9B4756C1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:57:00.334259 :9B4756C2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=54 time=1049192579 2025-05-24 07:57:00.334259 :9B4756C3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:57:00.334259 :9B4756C4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:57:00.334260 :9B4756C5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:57:00.334260 :9B4756C6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:57:00.334260 :9B4756C7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:57:00.334747 :9B4756C8: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-05-24 07:57:30.420374 :9B47607E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 07:58:00.404219 :9B476A44:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 07:58:00.393 2025-05-24 07:58:00.404221 :9B476A45:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12232 pso_num=25 pso_serial#=153 2025-05-24 07:58:00.404351 :9B476A46:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12232 osp_idx=23 osp_ver=113627 osp_pg=0 (spawn #213886) 2025-05-24 07:58:00.404352 :9B476A47:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:58:00.404353 :9B476A48:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=55 time=1049252657 2025-05-24 07:58:00.404353 :9B476A49:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:58:00.404355 :9B476A4A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:58:00.404356 :9B476A4B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:58:00.404356 :9B476A4C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:58:00.404356 :9B476A4D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:58:00.404833 :9B476A4E: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-05-24 07:58:30.495427 :9B47747C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 07:59:00.525813 :9B477E7D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 07:59:00.514 2025-05-24 07:59:00.525815 :9B477E7E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14008 pso_num=25 pso_serial#=154 2025-05-24 07:59:00.525954 :9B477E7F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14008 osp_idx=23 osp_ver=113628 osp_pg=0 (spawn #213888) 2025-05-24 07:59:00.525954 :9B477E80:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 07:59:00.525955 :9B477E81:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=56 time=1049312766 2025-05-24 07:59:00.525955 :9B477E82:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 07:59:00.525956 :9B477E83:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 07:59:00.525956 :9B477E84:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 07:59:00.525956 :9B477E85:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 07:59:00.525957 :9B477E86:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 07:59:00.526422 :9B477E87: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-05-24 07:59:30.616165 :9B478844:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:00:00.632150 :9B479205:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:00:00.620 2025-05-24 08:00:00.632152 :9B479206:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13688 pso_num=25 pso_serial#=155 2025-05-24 08:00:00.632308 :9B479207:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13688 osp_idx=23 osp_ver=113629 osp_pg=0 (spawn #213890) 2025-05-24 08:00:00.632309 :9B479208:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:00:00.632310 :9B479209:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=57 time=1049372875 2025-05-24 08:00:00.632310 :9B47920A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:00:00.632311 :9B47920B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:00:00.632311 :9B47920C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:00:00.632312 :9B47920D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:00:00.632312 :9B47920E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:00:00.632846 :9B47920F: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-05-24 08:00:30.714292 :9B479EE3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:01:00.702077 :9B47A8B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:01:00.691 2025-05-24 08:01:00.702079 :9B47A8B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9992 pso_num=25 pso_serial#=156 2025-05-24 08:01:00.702211 :9B47A8B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9992 osp_idx=23 osp_ver=113630 osp_pg=0 (spawn #213896) 2025-05-24 08:01:00.702211 :9B47A8B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:01:00.702212 :9B47A8BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=58 time=1049432954 2025-05-24 08:01:00.702212 :9B47A8BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:01:00.702212 :9B47A8BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:01:00.702213 :9B47A8BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:01:00.702213 :9B47A8BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:01:00.702213 :9B47A8BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:01:00.702690 :9B47A8C0: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-05-24 08:01:30.771386 :9B47B239:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:01:35.765863 :9B47B3E0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:01:35.754 2025-05-24 08:01:35.765866 :9B47B3E1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12244 pso_num=25 pso_serial#=157 2025-05-24 08:01:35.766000 :9B47B3E2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12244 osp_idx=23 osp_ver=113631 osp_pg=0 (spawn #213898) 2025-05-24 08:01:35.766001 :9B47B3E3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:01:35.766001 :9B47B3E4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=59 time=1049468016 2025-05-24 08:01:35.766001 :9B47B3E5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:01:35.766002 :9B47B3E6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:01:35.766002 :9B47B3E7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:01:35.766002 :9B47B3E8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:01:35.766003 :9B47B3E9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:01:35.766474 :9B47B3EA: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-05-24 08:02:00.801145 :9B47BC2B: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-05-24 08:02:30.920424 :9B47C5F6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:03:00.955121 :9B47CFE4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:03:00.944 2025-05-24 08:03:00.955123 :9B47CFE5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13868 pso_num=25 pso_serial#=158 2025-05-24 08:03:00.955261 :9B47CFE6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13868 osp_idx=23 osp_ver=113632 osp_pg=0 (spawn #213900) 2025-05-24 08:03:00.955262 :9B47CFE7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:03:00.955262 :9B47CFE8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=60 time=1049553204 2025-05-24 08:03:00.955262 :9B47CFE9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:03:00.955263 :9B47CFEA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:03:00.955263 :9B47CFEB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:03:00.955264 :9B47CFEC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:03:00.955264 :9B47CFED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:03:00.955757 :9B47CFEE: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-05-24 08:03:31.087495 :9B47DA34:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:04:01.060076 :9B47E6A2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:04:01.049 2025-05-24 08:04:01.060079 :9B47E6A3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6428 pso_num=25 pso_serial#=159 2025-05-24 08:04:01.060216 :9B47E6A4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6428 osp_idx=23 osp_ver=113633 osp_pg=0 (spawn #213902) 2025-05-24 08:04:01.060216 :9B47E6A5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:04:01.060217 :9B47E6A6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=61 time=1049613313 2025-05-24 08:04:01.060217 :9B47E6A7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:04:01.060218 :9B47E6A8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:04:01.060218 :9B47E6A9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:04:01.060218 :9B47E6AA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:04:01.060218 :9B47E6AB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:04:01.060710 :9B47E6AC: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-05-24 08:04:31.167663 :9B47F03E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:05:01.151427 :9B47F9EC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:05:01.140 2025-05-24 08:05:01.151429 :9B47F9ED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13628 pso_num=25 pso_serial#=160 2025-05-24 08:05:01.151573 :9B47F9EE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13628 osp_idx=23 osp_ver=113634 osp_pg=0 (spawn #213904) 2025-05-24 08:05:01.151574 :9B47F9EF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:05:01.151575 :9B47F9F0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=62 time=1049673407 2025-05-24 08:05:01.151575 :9B47F9F1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:05:01.151575 :9B47F9F2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:05:01.151576 :9B47F9F3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:05:01.151576 :9B47F9F4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:05:01.151577 :9B47F9F5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:05:01.152114 :9B47F9F6: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-05-24 08:05:31.298412 :9B480AB3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:06:01.272019 :9B481465:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:06:01.260 2025-05-24 08:06:01.272021 :9B481466:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12564 pso_num=25 pso_serial#=161 2025-05-24 08:06:01.272154 :9B481467:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12564 osp_idx=23 osp_ver=113635 osp_pg=0 (spawn #213906) 2025-05-24 08:06:01.272155 :9B481468:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:06:01.272155 :9B481469:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=63 time=1049733516 2025-05-24 08:06:01.272156 :9B48146A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:06:01.272156 :9B48146B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:06:01.272157 :9B48146C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:06:01.272157 :9B48146D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:06:01.272157 :9B48146E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:06:01.272712 :9B48146F: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-05-24 08:06:31.358266 :9B481E2A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:07:01.388519 :9B4827D2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:07:01.377 2025-05-24 08:07:01.388521 :9B4827D3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13968 pso_num=25 pso_serial#=162 2025-05-24 08:07:01.388653 :9B4827D4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13968 osp_idx=23 osp_ver=113636 osp_pg=0 (spawn #213908) 2025-05-24 08:07:01.388654 :9B4827D5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:07:01.388654 :9B4827D6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=64 time=1049793641 2025-05-24 08:07:01.388655 :9B4827D7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:07:01.388655 :9B4827D8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:07:01.388655 :9B4827D9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:07:01.388656 :9B4827DA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:07:01.388656 :9B4827DB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:07:01.389136 :9B4827DC: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-05-24 08:07:31.481192 :9B4831BB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:08:01.477789 :9B483B8B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:08:01.467 2025-05-24 08:08:01.477791 :9B483B8C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7776 pso_num=25 pso_serial#=163 2025-05-24 08:08:01.477931 :9B483B8D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7776 osp_idx=23 osp_ver=113637 osp_pg=0 (spawn #213910) 2025-05-24 08:08:01.477932 :9B483B8E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:08:01.477932 :9B483B8F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=65 time=1049853719 2025-05-24 08:08:01.477933 :9B483B90:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:08:01.477933 :9B483B91:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:08:01.477934 :9B483B92:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:08:01.477934 :9B483B93:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:08:01.477934 :9B483B94:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:08:01.478441 :9B483B95: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-05-24 08:08:31.566779 :9B48455B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:09:01.582544 :9B484F7A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:09:01.572 2025-05-24 08:09:01.582546 :9B484F7B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14012 pso_num=25 pso_serial#=164 2025-05-24 08:09:01.582688 :9B484F7C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14012 osp_idx=23 osp_ver=113638 osp_pg=0 (spawn #213913) 2025-05-24 08:09:01.582689 :9B484F7D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:09:01.582689 :9B484F7E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=66 time=1049913829 2025-05-24 08:09:01.582690 :9B484F7F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:09:01.582690 :9B484F80:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:09:01.582690 :9B484F81:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:09:01.582691 :9B484F82:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:09:01.582691 :9B484F83:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:09:01.583153 :9B484F84: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-05-24 08:09:31.689779 :9B485935:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:10:01.683744 :9B4862C0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:10:01.672 2025-05-24 08:10:01.683746 :9B4862C1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1112 pso_num=25 pso_serial#=165 2025-05-24 08:10:01.683873 :9B4862C2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1112 osp_idx=23 osp_ver=113639 osp_pg=0 (spawn #213915) 2025-05-24 08:10:01.683874 :9B4862C3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:10:01.683874 :9B4862C4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=67 time=1049973938 2025-05-24 08:10:01.683874 :9B4862C5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:10:01.683875 :9B4862C6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:10:01.683875 :9B4862C7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:10:01.683875 :9B4862C8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:10:01.683876 :9B4862C9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:10:01.684390 :9B4862CA: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-05-24 08:10:31.754383 :9B486DF4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-24 08:11:01.771874 :9B487777:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-24 08:11:01.761 2025-05-24 08:11:01.771876 :9B487778:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11720 pso_num=25 pso_serial#=166 2025-05-24 08:11:01.772016 :9B487779:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11720 osp_idx=23 osp_ver=113640 osp_pg=0 (spawn #213919) 2025-05-24 08:11:01.772017 :9B48777A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 08:11:01.772018 :9B48777B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=68 time=1050034016 2025-05-24 08:11:01.772018 :9B48777C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 08:11:01.772018 :9B48777D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 08:11:01.772019 :9B48777E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 08:11:01.772019 :9B48777F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 08:11:01.772020 :9B487780:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 08:11:01.772496 :9B487781: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: 11720, J001)