Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903221927\orcl_m002_22640_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:35089M/63366M, Ph+PgF:39971M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 22640, image: ORACLE.EXE (M002) *** 2025-09-03 22:19:27.714 *** SESSION ID:(193.38473) 2025-09-03 22:19:27.714 *** 2025-09-03 22:19:27.714 Process diagnostic dump for ORACLE.EXE (M002), OS id=22640, pid: 24, proc_ser: 97, sid: 193, sess_ser: 38473 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x942, blocks=0x1 wait_id=1213 seq_num=1214 snap_id=1 wait times: snap=0.001521 sec, exc=0.001521 sec, total=0.001521 sec wait times: max=infinite, heur=0.001521 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000362 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x922, blocks=0x1 wait_id=1212 seq_num=1213 snap_id=1 wait times: snap=0.002530 sec, exc=0.002530 sec, total=0.002530 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000228 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x90a, blocks=0x1 wait_id=1211 seq_num=1212 snap_id=1 wait times: snap=0.000391 sec, exc=0.000391 sec, total=0.000391 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000355 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x8e2, blocks=0x1 wait_id=1210 seq_num=1211 snap_id=1 wait times: snap=0.005179 sec, exc=0.005179 sec, total=0.005179 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000154 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x8ca, blocks=0x1 wait_id=1209 seq_num=1210 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000143 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x8b2, blocks=0x1 wait_id=1208 seq_num=1209 snap_id=1 wait times: snap=0.000061 sec, exc=0.000061 sec, total=0.000061 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.001045 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x7ba, blocks=0x1 wait_id=1207 seq_num=1208 snap_id=1 wait times: snap=0.000079 sec, exc=0.000079 sec, total=0.000079 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000346 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x792, blocks=0x1 wait_id=1206 seq_num=1207 snap_id=1 wait times: snap=0.002071 sec, exc=0.002071 sec, total=0.002071 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000497 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x1d4a, blocks=0x1 wait_id=1205 seq_num=1206 snap_id=1 wait times: snap=0.000109 sec, exc=0.000109 sec, total=0.000109 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000607 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x1b8a, blocks=0x1 wait_id=1204 seq_num=1205 snap_id=1 wait times: snap=0.002266 sec, exc=0.002266 sec, total=0.002266 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000148 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x7b2, blocks=0x1 wait_id=1203 seq_num=1204 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time Sampled Session History of session 193 serial 38473 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:19:27] waited for 'db file sequential read', seq_num: 1205 p1: 'file#'=0x2 p2: 'block#'=0x1b8a p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 22:19:26] idle wait at each sample [session created at: 22:19:27] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 22:19:27 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-03 22:19:27.714 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 22640, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-03 22:05:30.609791 :C89A2662:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:05:30.609791 :C89A2663:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:05:30.610277 :C89A2664: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-09-03 22:06:00.670020 :C89A31DA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:06:30.705983 :C89A3C03:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:06:30.694 2025-09-03 22:06:30.705986 :C89A3C04:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23420 pso_num=24 pso_serial#=82 2025-09-03 22:06:30.706109 :C89A3C05:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23420 osp_idx=22 osp_ver=837856 osp_pg=0 (spawn #555836) 2025-09-03 22:06:30.706110 :C89A3C06:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:06:30.706110 :C89A3C07:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=76 time=1322842002 2025-09-03 22:06:30.706111 :C89A3C08:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:06:30.706112 :C89A3C09:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:06:30.706112 :C89A3C0A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:06:30.706112 :C89A3C0B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:06:30.706113 :C89A3C0C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:06:30.706607 :C89A3C0D: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-09-03 22:07:00.813787 :C89A45DA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:07:30.789132 :C89A4FC0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:07:30.778 2025-09-03 22:07:30.789134 :C89A4FC1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22932 pso_num=24 pso_serial#=83 2025-09-03 22:07:30.789268 :C89A4FC2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22932 osp_idx=22 osp_ver=837857 osp_pg=0 (spawn #555838) 2025-09-03 22:07:30.789269 :C89A4FC3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:07:30.789270 :C89A4FC4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=77 time=1322902080 2025-09-03 22:07:30.789270 :C89A4FC5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:07:30.789271 :C89A4FC6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:07:30.789271 :C89A4FC7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:07:30.789271 :C89A4FC8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:07:30.789272 :C89A4FC9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:07:30.789737 :C89A4FCA: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-09-03 22:08:00.880286 :C89A5989:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:08:30.871259 :C89A6361:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:08:30.860 2025-09-03 22:08:30.871261 :C89A6362:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22188 pso_num=24 pso_serial#=84 2025-09-03 22:08:30.871378 :C89A6363:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22188 osp_idx=22 osp_ver=837858 osp_pg=0 (spawn #555840) 2025-09-03 22:08:30.871378 :C89A6364:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:08:30.871379 :C89A6365:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=78 time=1322962174 2025-09-03 22:08:30.871379 :C89A6366:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:08:30.871379 :C89A6367:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:08:30.871380 :C89A6368:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:08:30.871380 :C89A6369:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:08:30.871380 :C89A636A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:08:30.871885 :C89A636B: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-09-03 22:09:00.964125 :C89A6D84:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:09:30.987304 :C89A7798:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:09:30.976 2025-09-03 22:09:30.987308 :C89A7799:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21564 pso_num=24 pso_serial#=85 2025-09-03 22:09:30.987426 :C89A779A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21564 osp_idx=22 osp_ver=837859 osp_pg=0 (spawn #555843) 2025-09-03 22:09:30.987427 :C89A779B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:09:30.987427 :C89A779C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=79 time=1323022283 2025-09-03 22:09:30.987428 :C89A779D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:09:30.987428 :C89A779E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:09:30.987429 :C89A779F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:09:30.987429 :C89A77A0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:09:30.987429 :C89A77A1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:09:30.987908 :C89A77A2: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-09-03 22:10:01.060800 :C89A8196:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:10:31.114348 :C89A8BA6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:10:31.103 2025-09-03 22:10:31.114349 :C89A8BA7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13240 pso_num=24 pso_serial#=86 2025-09-03 22:10:31.114485 :C89A8BA8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13240 osp_idx=22 osp_ver=837860 osp_pg=0 (spawn #555845) 2025-09-03 22:10:31.114485 :C89A8BA9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:10:31.114486 :C89A8BAA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=80 time=1323082408 2025-09-03 22:10:31.114486 :C89A8BAB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:10:31.114487 :C89A8BAC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:10:31.114487 :C89A8BAD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:10:31.114488 :C89A8BAE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:10:31.114488 :C89A8BAF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:10:31.114959 :C89A8BB0: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-09-03 22:11:01.160442 :C89A9551:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:11:31.208838 :C89A9F89:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:11:31.197 2025-09-03 22:11:31.208840 :C89A9F8A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19536 pso_num=24 pso_serial#=87 2025-09-03 22:11:31.208954 :C89A9F8B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19536 osp_idx=22 osp_ver=837861 osp_pg=0 (spawn #555848) 2025-09-03 22:11:31.208954 :C89A9F8C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:11:31.208955 :C89A9F8D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=81 time=1323142502 2025-09-03 22:11:31.208955 :C89A9F8E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:11:31.208956 :C89A9F8F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:11:31.208956 :C89A9F90:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:11:31.208956 :C89A9F91:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:11:31.208957 :C89A9F92:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:11:31.209426 :C89A9F93: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-09-03 22:12:01.250151 :C89AA95C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:12:16.239493 :C89AAE84:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:12:16.228 2025-09-03 22:12:16.239496 :C89AAE85:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20488 pso_num=24 pso_serial#=88 2025-09-03 22:12:16.239625 :C89AAE86:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20488 osp_idx=22 osp_ver=837862 osp_pg=0 (spawn #555850) 2025-09-03 22:12:16.239626 :C89AAE87:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:12:16.239627 :C89AAE88:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=82 time=1323187533 2025-09-03 22:12:16.239627 :C89AAE89:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:12:16.239628 :C89AAE8A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:12:16.239628 :C89AAE8B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:12:16.239628 :C89AAE8C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:12:16.239629 :C89AAE8D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:12:16.240104 :C89AAE8E: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-09-03 22:12:31.252293 :C89AB380: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-09-03 22:13:01.354938 :C89ABE6C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:13:31.325156 :C89AC886:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:13:31.314 2025-09-03 22:13:31.325157 :C89AC887:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23956 pso_num=24 pso_serial#=89 2025-09-03 22:13:31.325286 :C89AC888:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23956 osp_idx=22 osp_ver=837863 osp_pg=0 (spawn #555852) 2025-09-03 22:13:31.325287 :C89AC889:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:13:31.325287 :C89AC88A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=83 time=1323262627 2025-09-03 22:13:31.325288 :C89AC88B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:13:31.325288 :C89AC88C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:13:31.325288 :C89AC88D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:13:31.325289 :C89AC88E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:13:31.325289 :C89AC88F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:13:31.325786 :C89AC890: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-09-03 22:14:01.390795 :C89AD20B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:14:31.424103 :C89ADC05:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:14:31.413 2025-09-03 22:14:31.424106 :C89ADC06:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20088 pso_num=24 pso_serial#=90 2025-09-03 22:14:31.424228 :C89ADC07:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20088 osp_idx=22 osp_ver=837864 osp_pg=0 (spawn #555854) 2025-09-03 22:14:31.424228 :C89ADC08:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:14:31.424229 :C89ADC09:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=84 time=1323322721 2025-09-03 22:14:31.424229 :C89ADC0A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:14:31.424230 :C89ADC0B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:14:31.424230 :C89ADC0C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:14:31.424231 :C89ADC0D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:14:31.424231 :C89ADC0E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:14:31.424730 :C89ADC0F: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-09-03 22:15:01.500049 :C89AE5DE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:15:31.535913 :C89AF359:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:15:31.524 2025-09-03 22:15:31.535914 :C89AF35A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20072 pso_num=24 pso_serial#=91 2025-09-03 22:15:31.536032 :C89AF35B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20072 osp_idx=22 osp_ver=837865 osp_pg=0 (spawn #555856) 2025-09-03 22:15:31.536033 :C89AF35C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:15:31.536033 :C89AF35D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=85 time=1323382830 2025-09-03 22:15:31.536033 :C89AF35E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:15:31.536034 :C89AF35F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:15:31.536034 :C89AF360:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:15:31.536035 :C89AF361:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:15:31.536035 :C89AF362:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:15:31.536534 :C89AF363: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-09-03 22:16:01.624601 :C89AFD96:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:16:31.617435 :C89B0761:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:16:31.606 2025-09-03 22:16:31.617437 :C89B0762:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23848 pso_num=24 pso_serial#=92 2025-09-03 22:16:31.617557 :C89B0763:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23848 osp_idx=22 osp_ver=837866 osp_pg=0 (spawn #555859) 2025-09-03 22:16:31.617557 :C89B0764:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:16:31.617558 :C89B0765:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=86 time=1323442908 2025-09-03 22:16:31.617558 :C89B0766:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:16:31.617558 :C89B0767:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:16:31.617559 :C89B0768:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:16:31.617559 :C89B0769:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:16:31.617559 :C89B076A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:16:31.618046 :C89B076B: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-09-03 22:17:01.709694 :C89B112D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:17:31.735059 :C89B1B41:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:17:31.724 2025-09-03 22:17:31.735060 :C89B1B42:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24012 pso_num=24 pso_serial#=93 2025-09-03 22:17:31.735184 :C89B1B43:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24012 osp_idx=22 osp_ver=837867 osp_pg=0 (spawn #555861) 2025-09-03 22:17:31.735185 :C89B1B44:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:17:31.735185 :C89B1B45:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=87 time=1323503033 2025-09-03 22:17:31.735186 :C89B1B46:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:17:31.735186 :C89B1B47:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:17:31.735187 :C89B1B48:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:17:31.735187 :C89B1B49:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:17:31.735187 :C89B1B4A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:17:31.735705 :C89B1B4B: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-09-03 22:18:01.786433 :C89B25C5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:18:31.810782 :C89B2FB8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:18:31.799 2025-09-03 22:18:31.810784 :C89B2FB9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19908 pso_num=24 pso_serial#=94 2025-09-03 22:18:31.810900 :C89B2FBA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19908 osp_idx=22 osp_ver=837868 osp_pg=0 (spawn #555863) 2025-09-03 22:18:31.810901 :C89B2FBB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:18:31.810901 :C89B2FBC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=88 time=1323563112 2025-09-03 22:18:31.810902 :C89B2FBD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:18:31.810902 :C89B2FBE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:18:31.810903 :C89B2FBF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:18:31.810903 :C89B2FC0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:18:31.810903 :C89B2FC1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:18:31.811379 :C89B2FC2: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-09-03 22:19:01.919439 :C89B39CA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:19:26.120499 :C89B7B56:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:19:26.105 2025-09-03 22:19:26.120502 :C89B7B57:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18472 pso_num=24 pso_serial#=95 2025-09-03 22:19:26.120678 :C89B7B58:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18472 osp_idx=22 osp_ver=837869 osp_pg=0 (spawn #555866) 2025-09-03 22:19:26.120678 :C89B7B59:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:19:26.120679 :C89B7B5A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=89 time=1323617408 2025-09-03 22:19:26.120679 :C89B7B5B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:19:26.120679 :C89B7B5C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:19:26.120680 :C89B7B5D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:19:26.120680 :C89B7B5E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:19:26.120680 :C89B7B5F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:19:26.122044 :C89B7B60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-03 22:19:26.122087 :C89B7B66:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:19:26.231488 :C89B7B98:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:19:26.553362 :C89B7B9B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:19:26.542 2025-09-03 22:19:26.553364 :C89B7B9C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24392 pso_num=24 pso_serial#=96 2025-09-03 22:19:26.553509 :C89B7B9D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24392 (legacy spawn) 2025-09-03 22:19:26.560438 :C89B7B9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:19:26.560734 :C89B7BA3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09-03 22:19:26.561619 :C89B7BA8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 22:19:27.428864 :C89B7C17:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 22:19:27.417 2025-09-03 22:19:27.428865 :C89B7C18:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22640 pso_num=24 pso_serial#=97 2025-09-03 22:19:27.428995 :C89B7C19:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22640 osp_idx=22 osp_ver=837871 osp_pg=0 (spawn #555868) 2025-09-03 22:19:27.428996 :C89B7C1A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 22:19:27.428996 :C89B7C1B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=90 time=1323618721 2025-09-03 22:19:27.428997 :C89B7C1C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 22:19:27.428997 :C89B7C1D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 22:19:27.428998 :C89B7C1E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 22:19:27.428998 :C89B7C1F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 22:19:27.428998 :C89B7C20:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 22:19:27.429500 :C89B7C21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-03 22:19:27.429545 :C89B7C27:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 22640, M002)