Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250730082413\orcl_m002_20424_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:35904M/63366M, Ph+PgF:41587M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 20424, image: ORACLE.EXE (M002) *** 2025-07-30 08:24:13.550 *** SESSION ID:(193.31577) 2025-07-30 08:24:13.550 *** 2025-07-30 08:24:13.550 Process diagnostic dump for ORACLE.EXE (M002), OS id=20424, pid: 24, proc_ser: 172, sid: 193, sess_ser: 31577 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x49a, blocks=0x1 wait_id=1192 seq_num=1193 snap_id=1 wait times: snap=0.002164 sec, exc=0.002164 sec, total=0.002164 sec wait times: max=infinite, heur=0.002164 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.000509 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x472, blocks=0x1 wait_id=1191 seq_num=1192 snap_id=1 wait times: snap=0.010320 sec, exc=0.010320 sec, total=0.010320 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000298 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x462, blocks=0x1 wait_id=1190 seq_num=1191 snap_id=1 wait times: snap=0.003017 sec, exc=0.003017 sec, total=0.003017 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000150 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x44a, blocks=0x1 wait_id=1189 seq_num=1190 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000148 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x442, blocks=0x1 wait_id=1188 seq_num=1189 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000215 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x432, blocks=0x1 wait_id=1187 seq_num=1188 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x40a, blocks=0x1 wait_id=1186 seq_num=1187 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.000158 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x3fa, blocks=0x1 wait_id=1185 seq_num=1186 snap_id=1 wait times: snap=0.000077 sec, exc=0.000077 sec, total=0.000077 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000545 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x3da, blocks=0x1 wait_id=1184 seq_num=1185 snap_id=1 wait times: snap=0.005473 sec, exc=0.005473 sec, total=0.005473 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.001165 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x322, blocks=0x1 wait_id=1183 seq_num=1184 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.000134 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x312, blocks=0x1 wait_id=1182 seq_num=1183 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.000132 sec of elapsed time Sampled Session History of session 193 serial 31577 --------------------------------------------------- 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, 08:24:13] idle wait at each sample [session created at: 08:24:13] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-30 08:24:13.550 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 20424, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-30 08:10:30.187768 :B8E6E900:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18004 osp_idx=22 osp_ver=637922 osp_pg=0 (spawn #437239) 2025-07-30 08:10:30.187769 :B8E6E901:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:10:30.187769 :B8E6E902:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=1 time=2543806079 2025-07-30 08:10:30.187769 :B8E6E903:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:10:30.187770 :B8E6E904:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:10:30.187770 :B8E6E905:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:10:30.187771 :B8E6E906:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:10:30.187771 :B8E6E907:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:10:30.188334 :B8E6E908:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:11:00.271276 :B8E6F402:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:11:30.252129 :B8E6FE31:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:11:30.241 2025-07-30 08:11:30.252131 :B8E6FE32:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8860 pso_num=24 pso_serial#=158 2025-07-30 08:11:30.252265 :B8E6FE33:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8860 osp_idx=22 osp_ver=637923 osp_pg=0 (spawn #437241) 2025-07-30 08:11:30.252265 :B8E6FE34:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:11:30.252266 :B8E6FE35:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=2 time=2543866142 2025-07-30 08:11:30.252266 :B8E6FE36:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:11:30.252267 :B8E6FE37:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:11:30.252267 :B8E6FE38:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:11:30.252267 :B8E6FE39:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:11:30.252267 :B8E6FE3A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:11:30.252756 :B8E6FE3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:12:00.323414 :B8E70848:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:12:30.334145 :B8E71270:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:12:30.323 2025-07-30 08:12:30.334147 :B8E71271:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1852 pso_num=24 pso_serial#=159 2025-07-30 08:12:30.334287 :B8E71272:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=1852 osp_idx=22 osp_ver=637924 osp_pg=0 (spawn #437244) 2025-07-30 08:12:30.334288 :B8E71273:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:12:30.334288 :B8E71274:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=3 time=2543926220 2025-07-30 08:12:30.334289 :B8E71275:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:12:30.334289 :B8E71276:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:12:30.334289 :B8E71277:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:12:30.334290 :B8E71278:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:12:30.334290 :B8E71279:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:12:30.334778 :B8E7127A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:13:00.418218 :B8E71C5B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:13:30.416366 :B8E7295B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:13:30.404 2025-07-30 08:13:30.416368 :B8E7295C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19272 pso_num=24 pso_serial#=160 2025-07-30 08:13:30.416504 :B8E7295D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19272 osp_idx=22 osp_ver=637925 osp_pg=0 (spawn #437246) 2025-07-30 08:13:30.416505 :B8E7295E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:13:30.416505 :B8E7295F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=4 time=2543986298 2025-07-30 08:13:30.416505 :B8E72960:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:13:30.416506 :B8E72961:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:13:30.416506 :B8E72962:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:13:30.416506 :B8E72963:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:13:30.416507 :B8E72964:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:13:30.417008 :B8E72965:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:14:00.475677 :B8E733C6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:14:30.513435 :B8E73DF6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:14:30.514 2025-07-30 08:14:30.513437 :B8E73DF7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18876 pso_num=24 pso_serial#=161 2025-07-30 08:14:30.513571 :B8E73DF8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18876 osp_idx=22 osp_ver=637926 osp_pg=0 (spawn #437248) 2025-07-30 08:14:30.513572 :B8E73DF9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:14:30.513573 :B8E73DFA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=5 time=2544046392 2025-07-30 08:14:30.513573 :B8E73DFB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:14:30.513574 :B8E73DFC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:14:30.513574 :B8E73DFD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:14:30.513574 :B8E73DFE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:14:30.513575 :B8E73DFF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:14:30.514074 :B8E73E00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:15:00.573602 :B8E7481D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:15:30.611549 :B8E75247:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:15:30.600 2025-07-30 08:15:30.611551 :B8E75248:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17060 pso_num=24 pso_serial#=162 2025-07-30 08:15:30.611725 :B8E75249:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17060 osp_idx=22 osp_ver=637927 osp_pg=0 (spawn #437251) 2025-07-30 08:15:30.611726 :B8E7524A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:15:30.611726 :B8E7524B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=6 time=2544106501 2025-07-30 08:15:30.611727 :B8E7524C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:15:30.611727 :B8E7524D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:15:30.611727 :B8E7524E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:15:30.611728 :B8E7524F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:15:30.611729 :B8E75250:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:15:30.612217 :B8E75251:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:16:00.684118 :B8E75C9C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:16:30.693093 :B8E76684:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:16:30.681 2025-07-30 08:16:30.693095 :B8E76685:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17888 pso_num=24 pso_serial#=163 2025-07-30 08:16:30.693232 :B8E76686:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17888 osp_idx=22 osp_ver=637928 osp_pg=0 (spawn #437253) 2025-07-30 08:16:30.693233 :B8E76687:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:16:30.693234 :B8E76688:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=7 time=2544166579 2025-07-30 08:16:30.693234 :B8E76689:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:16:30.693234 :B8E7668A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:16:30.693235 :B8E7668B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:16:30.693235 :B8E7668C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:16:30.693235 :B8E7668D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:16:30.693720 :B8E7668E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:16:55.692816 :B8E76ECD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-30 08:17:25.792273 :B8E778E7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:17:30.746273 :B8E77AF4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:17:30.734 2025-07-30 08:17:30.746275 :B8E77AF5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15264 pso_num=24 pso_serial#=164 2025-07-30 08:17:30.746390 :B8E77AF6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15264 osp_idx=22 osp_ver=637929 osp_pg=0 (spawn #437255) 2025-07-30 08:17:30.746390 :B8E77AF7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:17:30.746391 :B8E77AF8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=8 time=2544226626 2025-07-30 08:17:30.746391 :B8E77AF9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:17:30.746392 :B8E77AFA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:17:30.746392 :B8E77AFB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:17:30.746392 :B8E77AFC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:17:30.746393 :B8E77AFD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:17:30.746880 :B8E77AFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:18:00.798910 :B8E78514:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:18:30.807247 :B8E78F5E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:18:30.796 2025-07-30 08:18:30.807249 :B8E78F5F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17528 pso_num=24 pso_serial#=165 2025-07-30 08:18:30.807366 :B8E78F60:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17528 osp_idx=22 osp_ver=637930 osp_pg=0 (spawn #437257) 2025-07-30 08:18:30.807367 :B8E78F61:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:18:30.807367 :B8E78F62:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=9 time=2544286689 2025-07-30 08:18:30.807367 :B8E78F63:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:18:30.807368 :B8E78F64:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:18:30.807368 :B8E78F65:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:18:30.807369 :B8E78F66:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:18:30.807369 :B8E78F67:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:18:30.807854 :B8E78F68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:19:00.852083 :B8E7996F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:19:30.879687 :B8E7A37E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:19:30.869 2025-07-30 08:19:30.879689 :B8E7A37F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20212 pso_num=24 pso_serial#=166 2025-07-30 08:19:30.879827 :B8E7A380:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20212 osp_idx=22 osp_ver=637931 osp_pg=0 (spawn #437260) 2025-07-30 08:19:30.879828 :B8E7A381:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:19:30.879828 :B8E7A382:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=10 time=2544346767 2025-07-30 08:19:30.879829 :B8E7A383:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:19:30.879829 :B8E7A384:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:19:30.879829 :B8E7A385:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:19:30.879830 :B8E7A386:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:19:30.879830 :B8E7A387:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:19:30.880354 :B8E7A388:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:20:00.956847 :B8E7AD9A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:20:30.941260 :B8E7B79E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:20:30.930 2025-07-30 08:20:30.941262 :B8E7B79F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16104 pso_num=24 pso_serial#=167 2025-07-30 08:20:30.941382 :B8E7B7A0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16104 osp_idx=22 osp_ver=637932 osp_pg=0 (spawn #437262) 2025-07-30 08:20:30.941382 :B8E7B7A1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:20:30.941383 :B8E7B7A2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=11 time=2544406829 2025-07-30 08:20:30.941383 :B8E7B7A3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:20:30.941383 :B8E7B7A4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:20:30.941384 :B8E7B7A5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:20:30.941384 :B8E7B7A6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:20:30.941384 :B8E7B7A7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:20:30.941873 :B8E7B7A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:21:01.022209 :B8E7C284:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:21:31.015314 :B8E7CCA6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:21:31.004 2025-07-30 08:21:31.015316 :B8E7CCA7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17044 pso_num=24 pso_serial#=168 2025-07-30 08:21:31.015455 :B8E7CCA8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17044 osp_idx=22 osp_ver=637933 osp_pg=0 (spawn #437264) 2025-07-30 08:21:31.015455 :B8E7CCA9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:21:31.015456 :B8E7CCAA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=12 time=2544466908 2025-07-30 08:21:31.015456 :B8E7CCAB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:21:31.015456 :B8E7CCAC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:21:31.015457 :B8E7CCAD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:21:31.015457 :B8E7CCAE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:21:31.015457 :B8E7CCAF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:21:31.015927 :B8E7CCB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:22:01.120407 :B8E7D6AE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:22:31.104345 :B8E7E0DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:22:31.093 2025-07-30 08:22:31.104348 :B8E7E0E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12612 pso_num=24 pso_serial#=169 2025-07-30 08:22:31.104467 :B8E7E0E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12612 osp_idx=22 osp_ver=637934 osp_pg=0 (spawn #437267) 2025-07-30 08:22:31.104468 :B8E7E0E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:22:31.104468 :B8E7E0E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=13 time=2544526986 2025-07-30 08:22:31.104469 :B8E7E0E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:22:31.104469 :B8E7E0E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:22:31.104469 :B8E7E0E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:22:31.104470 :B8E7E0E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:22:31.104470 :B8E7E0E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:22:31.104954 :B8E7E0E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:23:01.164298 :B8E7EAE8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:23:31.205588 :B8E7F54B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:23:31.194 2025-07-30 08:23:31.205590 :B8E7F54C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10372 pso_num=24 pso_serial#=170 2025-07-30 08:23:31.205713 :B8E7F54D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10372 osp_idx=22 osp_ver=637935 osp_pg=0 (spawn #437269) 2025-07-30 08:23:31.205713 :B8E7F54E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:23:31.205714 :B8E7F54F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=14 time=2544587095 2025-07-30 08:23:31.205714 :B8E7F550:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:23:31.205714 :B8E7F551:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:23:31.205714 :B8E7F552:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:23:31.205715 :B8E7F553:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:23:31.205715 :B8E7F554:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:23:31.206186 :B8E7F555:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-30 08:24:01.293650 :B8E7FFAE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:24:10.970336 :B8E83C3E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:24:10.971 2025-07-30 08:24:10.970339 :B8E83C3F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14620 pso_num=24 pso_serial#=171 2025-07-30 08:24:10.970535 :B8E83C40:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14620 osp_idx=22 osp_ver=637936 osp_pg=0 (spawn #437271) 2025-07-30 08:24:10.970536 :B8E83C41:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:24:10.970536 :B8E83C42:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=15 time=2544626861 2025-07-30 08:24:10.970537 :B8E83C43:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:24:10.970537 :B8E83C44:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:24:10.970538 :B8E83C45:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:24:10.970538 :B8E83C46:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:24:10.970538 :B8E83C47:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:24:10.971407 :B8E83C48: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-07-30 08:24:10.971454 :B8E83C4E: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-07-30 08:24:11.096697 :B8E83C8C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-30 08:24:13.302493 :B8E83D95:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-30 08:24:13.299 2025-07-30 08:24:13.302495 :B8E83D96:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20424 pso_num=24 pso_serial#=172 2025-07-30 08:24:13.302640 :B8E83D97:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20424 osp_idx=22 osp_ver=637937 osp_pg=0 (spawn #437273) 2025-07-30 08:24:13.302641 :B8E83D98:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-30 08:24:13.302641 :B8E83D99:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=16 time=2544629189 2025-07-30 08:24:13.302642 :B8E83D9A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-30 08:24:13.302642 :B8E83D9B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-30 08:24:13.302642 :B8E83D9C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-30 08:24:13.302642 :B8E83D9D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-30 08:24:13.302643 :B8E83D9E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-30 08:24:13.303163 :B8E83D9F: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-07-30 08:24:13.303207 :B8E83DA5: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: 20424, M002)