Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250807082951\orcl_m002_14108_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:35672M/63366M, Ph+PgF:41173M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14108, image: ORACLE.EXE (M002) *** 2025-08-07 08:29:51.323 *** SESSION ID:(201.49957) 2025-08-07 08:29:51.323 *** 2025-08-07 08:29:51.323 Process diagnostic dump for ORACLE.EXE (M002), OS id=14108, pid: 25, proc_ser: 243, sid: 201, sess_ser: 49957 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x35aa, blocks=0x1 wait_id=1436 seq_num=1437 snap_id=1 wait times: snap=0.000691 sec, exc=0.000691 sec, total=0.000691 sec wait times: max=infinite, heur=0.000691 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.000373 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x353a, blocks=0x1 wait_id=1435 seq_num=1436 snap_id=1 wait times: snap=0.005734 sec, exc=0.005734 sec, total=0.005734 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000328 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x34ca, blocks=0x1 wait_id=1434 seq_num=1435 snap_id=1 wait times: snap=0.001991 sec, exc=0.001991 sec, total=0.001991 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000350 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x345a, blocks=0x1 wait_id=1433 seq_num=1434 snap_id=1 wait times: snap=0.004296 sec, exc=0.004296 sec, total=0.004296 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000354 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x33ea, blocks=0x1 wait_id=1432 seq_num=1433 snap_id=1 wait times: snap=0.002913 sec, exc=0.002913 sec, total=0.002913 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000365 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x333a, blocks=0x1 wait_id=1431 seq_num=1432 snap_id=1 wait times: snap=0.002961 sec, exc=0.002961 sec, total=0.002961 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000297 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x327a, blocks=0x1 wait_id=1430 seq_num=1431 snap_id=1 wait times: snap=0.001409 sec, exc=0.001409 sec, total=0.001409 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000383 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x31ba, blocks=0x1 wait_id=1429 seq_num=1430 snap_id=1 wait times: snap=0.004336 sec, exc=0.004336 sec, total=0.004336 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000353 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x30fa, blocks=0x1 wait_id=1428 seq_num=1429 snap_id=1 wait times: snap=0.005150 sec, exc=0.005150 sec, total=0.005150 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000376 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x2aea, blocks=0x1 wait_id=1427 seq_num=1428 snap_id=1 wait times: snap=0.003451 sec, exc=0.003451 sec, total=0.003451 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000185 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x267a, blocks=0x1 wait_id=1426 seq_num=1427 snap_id=1 wait times: snap=0.000060 sec, exc=0.000060 sec, total=0.000060 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000176 sec of elapsed time Sampled Session History of session 201 serial 49957 --------------------------------------------------- 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:29:51] waited for 'db file sequential read', seq_num: 1425 p1: 'file#'=0x2 p2: 'block#'=0x265a p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:29:50] idle wait at each sample [session created at: 08:29:50] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:29:51 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-07 08:29:51.323 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14108, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-07 08:16:53.155769 :BC7938E3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21032 osp_idx=23 osp_ver=230604 osp_pg=0 (spawn #463911) 2025-08-07 08:16:53.155769 :BC7938E4: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-07 08:16:53.155769 :BC7938E5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=75 time=3235282454 2025-08-07 08:16:53.155770 :BC7938E6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:16:53.155771 :BC7938E7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:16:53.155771 :BC7938E8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:16:53.155771 :BC7938E9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:16:53.155772 :BC7938EA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:16:53.156230 :BC7938EB: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-07 08:17:23.222673 :BC7942BC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:17:53.221390 :BC794C91:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:17:53.210 2025-08-07 08:17:53.221391 :BC794C92:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20688 pso_num=25 pso_serial#=228 2025-08-07 08:17:53.221520 :BC794C93:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20688 osp_idx=23 osp_ver=230605 osp_pg=0 (spawn #463913) 2025-08-07 08:17:53.221521 :BC794C94: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-07 08:17:53.221521 :BC794C95:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=76 time=3235342517 2025-08-07 08:17:53.221522 :BC794C96:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:17:53.221523 :BC794C97:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:17:53.221524 :BC794C98:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:17:53.221524 :BC794C99:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:17:53.221525 :BC794C9A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:17:53.222000 :BC794C9B: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-07 08:18:23.297425 :BC795715:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:18:53.301980 :BC7960C7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:18:53.291 2025-08-07 08:18:53.301982 :BC7960C8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6128 pso_num=25 pso_serial#=229 2025-08-07 08:18:53.302104 :BC7960C9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6128 osp_idx=23 osp_ver=230606 osp_pg=0 (spawn #463915) 2025-08-07 08:18:53.302104 :BC7960CA: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-07 08:18:53.302105 :BC7960CB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=77 time=3235402595 2025-08-07 08:18:53.302105 :BC7960CC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:18:53.302106 :BC7960CD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:18:53.302106 :BC7960CE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:18:53.302107 :BC7960CF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:18:53.302107 :BC7960D0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:18:53.302578 :BC7960D1: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-07 08:19:23.407846 :BC796A87:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:19:53.393163 :BC7974D1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:19:53.382 2025-08-07 08:19:53.393165 :BC7974D2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9092 pso_num=25 pso_serial#=230 2025-08-07 08:19:53.393287 :BC7974D3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9092 osp_idx=23 osp_ver=230607 osp_pg=0 (spawn #463918) 2025-08-07 08:19:53.393288 :BC7974D4: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-07 08:19:53.393288 :BC7974D5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=78 time=3235462689 2025-08-07 08:19:53.393288 :BC7974D6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:19:53.393289 :BC7974D7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:19:53.393289 :BC7974D8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:19:53.393289 :BC7974D9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:19:53.393290 :BC7974DA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:19:53.393763 :BC7974DB: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-07 08:20:23.462655 :BC797EAC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:20:53.480393 :BC7988B2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:20:53.469 2025-08-07 08:20:53.480394 :BC7988B3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20260 pso_num=25 pso_serial#=231 2025-08-07 08:20:53.480516 :BC7988B4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20260 osp_idx=23 osp_ver=230608 osp_pg=0 (spawn #463920) 2025-08-07 08:20:53.480517 :BC7988B5: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-07 08:20:53.480517 :BC7988B6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=79 time=3235522783 2025-08-07 08:20:53.480518 :BC7988B7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:20:53.480518 :BC7988B8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:20:53.480518 :BC7988B9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:20:53.480519 :BC7988BA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:20:53.480519 :BC7988BB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:20:53.480993 :BC7988BC: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-07 08:21:23.546400 :BC7992D4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:21:53.581324 :BC799C6D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:21:53.570 2025-08-07 08:21:53.581326 :BC799C6E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17792 pso_num=25 pso_serial#=232 2025-08-07 08:21:53.581450 :BC799C6F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17792 osp_idx=23 osp_ver=230609 osp_pg=0 (spawn #463922) 2025-08-07 08:21:53.581451 :BC799C70: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-07 08:21:53.581451 :BC799C71:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=80 time=3235582876 2025-08-07 08:21:53.581452 :BC799C72:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:21:53.581452 :BC799C73:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:21:53.581452 :BC799C74:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:21:53.581453 :BC799C75:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:21:53.581453 :BC799C76:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:21:53.581934 :BC799C77: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-07 08:22:23.640802 :BC79A673:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:22:53.665644 :BC79B042:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:22:53.655 2025-08-07 08:22:53.665646 :BC79B043:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17392 pso_num=25 pso_serial#=233 2025-08-07 08:22:53.665781 :BC79B044:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17392 osp_idx=23 osp_ver=230610 osp_pg=0 (spawn #463924) 2025-08-07 08:22:53.665781 :BC79B045: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-07 08:22:53.665782 :BC79B046:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=81 time=3235642970 2025-08-07 08:22:53.665782 :BC79B047:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:22:53.665782 :BC79B048:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:22:53.665783 :BC79B049:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:22:53.665783 :BC79B04A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:22:53.665783 :BC79B04B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:22:53.666260 :BC79B04C: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-07 08:23:08.679208 :BC79B639: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-07 08:23:38.775639 :BC79BFBF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:23:53.748518 :BC79C4E8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:23:53.737 2025-08-07 08:23:53.748520 :BC79C4E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13728 pso_num=25 pso_serial#=234 2025-08-07 08:23:53.748638 :BC79C4EA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13728 osp_idx=23 osp_ver=230611 osp_pg=0 (spawn #463926) 2025-08-07 08:23:53.748639 :BC79C4EB: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-07 08:23:53.748640 :BC79C4EC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=82 time=3235703048 2025-08-07 08:23:53.748641 :BC79C4ED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:23:53.748641 :BC79C4EE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:23:53.748642 :BC79C4EF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:23:53.748642 :BC79C4F0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:23:53.748642 :BC79C4F1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:23:53.749137 :BC79C4F2: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-07 08:24:23.831377 :BC79CEE0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:24:53.826534 :BC79D89D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:24:53.815 2025-08-07 08:24:53.826537 :BC79D89E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19608 pso_num=25 pso_serial#=235 2025-08-07 08:24:53.826675 :BC79D89F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19608 osp_idx=23 osp_ver=230612 osp_pg=0 (spawn #463929) 2025-08-07 08:24:53.826675 :BC79D8A0: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-07 08:24:53.826676 :BC79D8A1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=83 time=3235763126 2025-08-07 08:24:53.826676 :BC79D8A2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:24:53.826677 :BC79D8A3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:24:53.826677 :BC79D8A4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:24:53.826677 :BC79D8A5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:24:53.826678 :BC79D8A6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:24:53.827180 :BC79D8A7: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-07 08:25:23.896070 :BC79E253:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:25:53.930951 :BC79EC3D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:25:53.930 2025-08-07 08:25:53.930953 :BC79EC3E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12464 pso_num=25 pso_serial#=236 2025-08-07 08:25:53.931081 :BC79EC3F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12464 osp_idx=23 osp_ver=230613 osp_pg=0 (spawn #463931) 2025-08-07 08:25:53.931081 :BC79EC40: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-07 08:25:53.931082 :BC79EC41:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=84 time=3235823236 2025-08-07 08:25:53.931082 :BC79EC42:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:25:53.931083 :BC79EC43:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:25:53.931084 :BC79EC44:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:25:53.931085 :BC79EC45:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:25:53.931085 :BC79EC46:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:25:53.931565 :BC79EC47: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-07 08:26:24.006176 :BC79F6A6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:26:54.007011 :BC7A008F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:26:54.007 2025-08-07 08:26:54.007012 :BC7A0090:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4708 pso_num=25 pso_serial#=237 2025-08-07 08:26:54.007128 :BC7A0091:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4708 osp_idx=23 osp_ver=230614 osp_pg=0 (spawn #463933) 2025-08-07 08:26:54.007129 :BC7A0092: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-07 08:26:54.007129 :BC7A0093:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=3235883314 2025-08-07 08:26:54.007129 :BC7A0094:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:26:54.007130 :BC7A0095:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:26:54.007130 :BC7A0096:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:26:54.007130 :BC7A0097:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:26:54.007131 :BC7A0098:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:26:54.007612 :BC7A0099: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-07 08:27:24.127668 :BC7A0A63:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:27:54.154616 :BC7A1448:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:27:54.143 2025-08-07 08:27:54.154618 :BC7A1449:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21408 pso_num=25 pso_serial#=238 2025-08-07 08:27:54.154738 :BC7A144A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21408 osp_idx=23 osp_ver=230615 osp_pg=0 (spawn #463935) 2025-08-07 08:27:54.154738 :BC7A144B: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-07 08:27:54.154738 :BC7A144C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=86 time=3235943454 2025-08-07 08:27:54.154739 :BC7A144D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:27:54.154739 :BC7A144E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:27:54.154740 :BC7A144F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:27:54.154740 :BC7A1450:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:27:54.154740 :BC7A1451:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:27:54.155223 :BC7A1452: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-07 08:28:24.251979 :BC7A1EA3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:28:54.251400 :BC7A28A8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:28:54.240 2025-08-07 08:28:54.251403 :BC7A28A9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4148 pso_num=25 pso_serial#=239 2025-08-07 08:28:54.251521 :BC7A28AA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4148 osp_idx=23 osp_ver=230616 osp_pg=0 (spawn #463937) 2025-08-07 08:28:54.251522 :BC7A28AB: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-07 08:28:54.251522 :BC7A28AC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=87 time=3236003548 2025-08-07 08:28:54.251523 :BC7A28AD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:28:54.251523 :BC7A28AE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:28:54.251524 :BC7A28AF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:28:54.251524 :BC7A28B0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:28:54.251524 :BC7A28B1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:28:54.252013 :BC7A28B2: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-07 08:29:24.350171 :BC7A327F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:29:48.186495 :BC7A7417:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:29:48.172 2025-08-07 08:29:48.186497 :BC7A7418:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14080 pso_num=25 pso_serial#=240 2025-08-07 08:29:48.186644 :BC7A7419:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14080 (legacy spawn) 2025-08-07 08:29:48.193226 :BC7A741A: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-07 08:29:48.193677 :BC7A7423: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-07 08:29:48.194553 :BC7A7424:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:29:48.203155 :BC7A7427:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:29:48.203 2025-08-07 08:29:48.203158 :BC7A7428:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20136 pso_num=25 pso_serial#=241 2025-08-07 08:29:48.203317 :BC7A7429:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20136 (legacy spawn) 2025-08-07 08:29:48.209752 :BC7A742A: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-07 08:29:48.210061 :BC7A7433: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-07 08:29:48.210920 :BC7A7434:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:29:48.533568 :BC7A74D0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:29:48.531 2025-08-07 08:29:48.533569 :BC7A74D1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19356 pso_num=25 pso_serial#=242 2025-08-07 08:29:48.533732 :BC7A74D2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19356 osp_idx=23 osp_ver=230619 osp_pg=0 (spawn #463941) 2025-08-07 08:29:48.533732 :BC7A74D3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-07 08:29:48.533733 :BC7A74D4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=3236057829 2025-08-07 08:29:48.533733 :BC7A74D5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:29:48.533734 :BC7A74D6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:29:48.533734 :BC7A74D7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:29:48.533735 :BC7A74D8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:29:48.533735 :BC7A74D9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:29:48.535123 :BC7A74DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-07 08:29:48.535172 :BC7A74E0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-07 08:29:48.641559 :BC7A7512:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-07 08:29:50.832263 :BC7A7615:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-07 08:29:50.828 2025-08-07 08:29:50.832265 :BC7A7616:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14108 pso_num=25 pso_serial#=243 2025-08-07 08:29:50.832396 :BC7A7617:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14108 osp_idx=23 osp_ver=230620 osp_pg=0 (spawn #463943) 2025-08-07 08:29:50.832396 :BC7A7618:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-07 08:29:50.832397 :BC7A7619:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=3236060126 2025-08-07 08:29:50.832397 :BC7A761A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-07 08:29:50.832397 :BC7A761B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-07 08:29:50.832398 :BC7A761C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-07 08:29:50.832398 :BC7A761D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-07 08:29:50.832398 :BC7A761E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-07 08:29:50.832887 :BC7A761F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-07 08:29:50.832931 :BC7A7625:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 14108, M002)