Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250826081429\orcl_j000_23276_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:35196M/63366M, Ph+PgF:40257M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23276, image: ORACLE.EXE (J000) *** 2025-08-26 08:14:29.543 *** SESSION ID:(201.48173) 2025-08-26 08:14:29.543 *** 2025-08-26 08:14:29.543 Process diagnostic dump for ORACLE.EXE (J000), OS id=23276, pid: 25, proc_ser: 20, sid: 201, sess_ser: 48173 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23276 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.366113 sec, exc=0.366113 sec, total=0.366113 sec wait times: max=0.500000 sec, heur=5.852322 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.000069 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 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=5 seq_num=6 snap_id=1 wait times: snap=0.500295 sec, exc=0.500295 sec, total=0.500295 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=4 seq_num=5 snap_id=1 wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500156 sec, exc=0.500156 sec, total=0.500156 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=2 seq_num=3 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time Sampled Session History of session 201 serial 48173 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 08:14:23 - 08:14:29] idle wait at each sample [session created at: 08:14:23] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-26 08:14:29.543 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23276, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-26 08:00:30.293191 :C4CDE8BC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:00:37.501060 :C4CDEC4C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:00:37.490 2025-08-26 08:00:37.501062 :C4CDEC4D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19952 pso_num=25 pso_serial#=5 2025-08-26 08:00:37.501180 :C4CDEC4E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19952 osp_idx=23 osp_ver=259707 osp_pg=0 (spawn #527220) 2025-08-26 08:00:37.501180 :C4CDEC4F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:00:37.501181 :C4CDEC50:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=79 time=580892237 2025-08-26 08:00:37.501181 :C4CDEC51:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:00:37.501181 :C4CDEC52:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:00:37.501182 :C4CDEC53:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:00:37.501182 :C4CDEC54:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:00:37.501183 :C4CDEC55:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:00:37.501670 :C4CDEC56: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-08-26 08:01:07.575416 :C4CDF68A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:01:37.562244 :C4CE0064:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:01:37.550 2025-08-26 08:01:37.562246 :C4CE0065:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22916 pso_num=25 pso_serial#=6 2025-08-26 08:01:37.562365 :C4CE0066:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22916 osp_idx=23 osp_ver=259708 osp_pg=0 (spawn #527222) 2025-08-26 08:01:37.562366 :C4CE0067:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:01:37.562367 :C4CE0068:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=80 time=580952299 2025-08-26 08:01:37.562367 :C4CE0069:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:01:37.562368 :C4CE006A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:01:37.562368 :C4CE006B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:01:37.562368 :C4CE006C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:01:37.562369 :C4CE006D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:01:37.562862 :C4CE006E: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-08-26 08:02:07.629302 :C4CE0A86:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:02:37.650006 :C4CE1409:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:02:37.638 2025-08-26 08:02:37.650008 :C4CE140A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23076 pso_num=25 pso_serial#=7 2025-08-26 08:02:37.650131 :C4CE140B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23076 osp_idx=23 osp_ver=259709 osp_pg=0 (spawn #527224) 2025-08-26 08:02:37.650131 :C4CE140C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:02:37.650132 :C4CE140D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=81 time=581012377 2025-08-26 08:02:37.650132 :C4CE140E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:02:37.650133 :C4CE140F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:02:37.650133 :C4CE1410:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:02:37.650133 :C4CE1411:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:02:37.650133 :C4CE1412:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:02:37.650628 :C4CE1413: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-08-26 08:03:07.735681 :C4CE1D84:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:03:37.743518 :C4CE2751:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:03:37.732 2025-08-26 08:03:37.743521 :C4CE2752:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21492 pso_num=25 pso_serial#=8 2025-08-26 08:03:37.743640 :C4CE2753:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21492 osp_idx=23 osp_ver=259710 osp_pg=0 (spawn #527227) 2025-08-26 08:03:37.743641 :C4CE2754:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:03:37.743641 :C4CE2755:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=82 time=581072471 2025-08-26 08:03:37.743642 :C4CE2756:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:03:37.743642 :C4CE2757:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:03:37.743642 :C4CE2758:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:03:37.743643 :C4CE2759:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:03:37.743643 :C4CE275A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:03:37.744145 :C4CE275B: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-08-26 08:04:07.844543 :C4CE30CE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:04:22.817867 :C4CE3572:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:04:22.806 2025-08-26 08:04:22.817869 :C4CE3573:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22332 pso_num=25 pso_serial#=9 2025-08-26 08:04:22.818001 :C4CE3574:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22332 osp_idx=23 osp_ver=259711 osp_pg=0 (spawn #527229) 2025-08-26 08:04:22.818002 :C4CE3575:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:04:22.818002 :C4CE3576:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=83 time=581117549 2025-08-26 08:04:22.818003 :C4CE3577:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:04:22.818003 :C4CE3578:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:04:22.818004 :C4CE3579:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:04:22.818004 :C4CE357A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:04:22.818004 :C4CE357B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:04:22.818485 :C4CE357C: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-08-26 08:04:37.833919 :C4CE3A7C: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-08-26 08:05:07.914615 :C4CE43FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:05:37.922596 :C4CE52EA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:05:37.911 2025-08-26 08:05:37.922598 :C4CE52EB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20984 pso_num=25 pso_serial#=10 2025-08-26 08:05:37.922736 :C4CE52EC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20984 osp_idx=23 osp_ver=259712 osp_pg=0 (spawn #527231) 2025-08-26 08:05:37.922736 :C4CE52ED:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:05:37.922737 :C4CE52EE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=84 time=581192658 2025-08-26 08:05:37.922737 :C4CE52EF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:05:37.922738 :C4CE52F0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:05:37.922738 :C4CE52F1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:05:37.922738 :C4CE52F2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:05:37.922738 :C4CE52F3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:05:37.923228 :C4CE52F4: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-08-26 08:06:07.997192 :C4CE5D46:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:06:37.986943 :C4CE66E9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:06:37.975 2025-08-26 08:06:37.986945 :C4CE66EA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22992 pso_num=25 pso_serial#=11 2025-08-26 08:06:37.987068 :C4CE66EB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22992 osp_idx=23 osp_ver=259713 osp_pg=0 (spawn #527233) 2025-08-26 08:06:37.987069 :C4CE66EC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:06:37.987069 :C4CE66ED:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=581252721 2025-08-26 08:06:37.987069 :C4CE66EE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:06:37.987070 :C4CE66EF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:06:37.987070 :C4CE66F0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:06:37.987071 :C4CE66F1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:06:37.987071 :C4CE66F2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:06:37.987548 :C4CE66F3: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-08-26 08:07:08.047317 :C4CE717F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:07:38.070327 :C4CE7B13:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:07:38.059 2025-08-26 08:07:38.070330 :C4CE7B14:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21088 pso_num=25 pso_serial#=12 2025-08-26 08:07:38.070465 :C4CE7B15:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21088 osp_idx=23 osp_ver=259714 osp_pg=0 (spawn #527235) 2025-08-26 08:07:38.070466 :C4CE7B16:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:07:38.070467 :C4CE7B17:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=86 time=581312799 2025-08-26 08:07:38.070467 :C4CE7B18:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:07:38.070468 :C4CE7B19:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:07:38.070468 :C4CE7B1A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:07:38.070469 :C4CE7B1B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:07:38.070469 :C4CE7B1C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:07:38.070982 :C4CE7B1D: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-08-26 08:08:08.184048 :C4CE8492:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:08:38.170201 :C4CE8DF3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:08:38.158 2025-08-26 08:08:38.170203 :C4CE8DF4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22724 pso_num=25 pso_serial#=13 2025-08-26 08:08:38.170334 :C4CE8DF5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22724 osp_idx=23 osp_ver=259715 osp_pg=0 (spawn #527237) 2025-08-26 08:08:38.170335 :C4CE8DF6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:08:38.170335 :C4CE8DF7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=87 time=581372908 2025-08-26 08:08:38.170336 :C4CE8DF8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:08:38.170337 :C4CE8DF9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:08:38.170337 :C4CE8DFA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:08:38.170338 :C4CE8DFB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:08:38.170338 :C4CE8DFC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:08:38.170835 :C4CE8DFD: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-08-26 08:09:08.256169 :C4CE9775:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:09:38.252352 :C4CEA125:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:09:38.240 2025-08-26 08:09:38.252355 :C4CEA126:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19672 pso_num=25 pso_serial#=14 2025-08-26 08:09:38.252507 :C4CEA127:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19672 osp_idx=23 osp_ver=259716 osp_pg=0 (spawn #527239) 2025-08-26 08:09:38.252507 :C4CEA128:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:09:38.252507 :C4CEA129:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=581432987 2025-08-26 08:09:38.252508 :C4CEA12A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:09:38.252508 :C4CEA12B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:09:38.252508 :C4CEA12C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:09:38.252509 :C4CEA12D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:09:38.252509 :C4CEA12E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:09:38.252991 :C4CEA12F: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-08-26 08:10:08.338766 :C4CEAADD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:10:38.337119 :C4CEB4E4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:10:38.326 2025-08-26 08:10:38.337121 :C4CEB4E5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23404 pso_num=25 pso_serial#=15 2025-08-26 08:10:38.337248 :C4CEB4E6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23404 osp_idx=23 osp_ver=259717 osp_pg=0 (spawn #527242) 2025-08-26 08:10:38.337248 :C4CEB4E7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:10:38.337249 :C4CEB4E8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=581493065 2025-08-26 08:10:38.337249 :C4CEB4E9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:10:38.337250 :C4CEB4EA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:10:38.337250 :C4CEB4EB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:10:38.337250 :C4CEB4EC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:10:38.337251 :C4CEB4ED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:10:38.337729 :C4CEB4EE: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-08-26 08:11:08.417804 :C4CEBE6A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:11:38.435145 :C4CECA9B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:11:38.424 2025-08-26 08:11:38.435147 :C4CECA9C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23400 pso_num=25 pso_serial#=16 2025-08-26 08:11:38.435286 :C4CECA9D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23400 osp_idx=23 osp_ver=259718 osp_pg=0 (spawn #527244) 2025-08-26 08:11:38.435287 :C4CECA9E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:11:38.435287 :C4CECA9F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=581553174 2025-08-26 08:11:38.435288 :C4CECAA0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:11:38.435288 :C4CECAA1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:11:38.435289 :C4CECAA2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:11:38.435289 :C4CECAA3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:11:38.435289 :C4CECAA4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:11:38.435791 :C4CECAA5: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-08-26 08:12:08.523257 :C4CED4B3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:12:38.520407 :C4CEDE3D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:12:38.509 2025-08-26 08:12:38.520409 :C4CEDE3E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20192 pso_num=25 pso_serial#=17 2025-08-26 08:12:38.520547 :C4CEDE3F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20192 osp_idx=23 osp_ver=259719 osp_pg=0 (spawn #527246) 2025-08-26 08:12:38.520548 :C4CEDE40:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:12:38.520548 :C4CEDE41:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=581613252 2025-08-26 08:12:38.520548 :C4CEDE42:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:12:38.520549 :C4CEDE43:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:12:38.520550 :C4CEDE44:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:12:38.520550 :C4CEDE45:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:12:38.520550 :C4CEDE46:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:12:38.521102 :C4CEDE47: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-08-26 08:13:08.584461 :C4CEE79B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:13:38.604173 :C4CEF118:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:13:38.593 2025-08-26 08:13:38.604175 :C4CEF119:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22648 pso_num=25 pso_serial#=18 2025-08-26 08:13:38.604305 :C4CEF11A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22648 osp_idx=23 osp_ver=259720 osp_pg=0 (spawn #527249) 2025-08-26 08:13:38.604306 :C4CEF11B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:13:38.604306 :C4CEF11C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=581673330 2025-08-26 08:13:38.604307 :C4CEF11D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:13:38.604307 :C4CEF11E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:13:38.604307 :C4CEF11F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:13:38.604308 :C4CEF120:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:13:38.604308 :C4CEF121:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:13:38.604805 :C4CEF122: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-08-26 08:14:08.682192 :C4CEFA65:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:14:08.696275 :C4CEFA75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:14:08.682 2025-08-26 08:14:08.696277 :C4CEFA76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23468 pso_num=25 pso_serial#=19 2025-08-26 08:14:08.696421 :C4CEFA77:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23468 (legacy spawn) 2025-08-26 08:14:08.703522 :C4CEFA7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-26 08:14:08.703690 :C4CEFA84:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-26 08:14:08.704500 :C4CEFA85:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-26 08:14:23.688374 :C4CF34BF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-26 08:14:23.683 2025-08-26 08:14:23.688376 :C4CF34C0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23276 pso_num=25 pso_serial#=20 2025-08-26 08:14:23.688513 :C4CF34C1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23276 osp_idx=23 osp_ver=259722 osp_pg=0 (spawn #527251) 2025-08-26 08:14:23.688514 :C4CF34C2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 08:14:23.688514 :C4CF34C3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=581718424 2025-08-26 08:14:23.688515 :C4CF34C4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 08:14:23.688517 :C4CF34C5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 08:14:23.688517 :C4CF34C6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 08:14:23.688518 :C4CF34C7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 08:14:23.688518 :C4CF34C8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 08:14:23.689018 :C4CF34C9: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-08-26 08:14:23.689649 :C4CF34DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-26 08:14:23.695177 :C4CF34FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-26 08:14:23.696125 :C4CF3502:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-26 08:14:23.696247 :C4CF350B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 23276, J000)