Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916082601\orcl_m002_25364_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:34707M/63366M, Ph+PgF:39264M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 25364, image: ORACLE.EXE (M002) *** 2025-09-16 08:26:01.132 *** SESSION ID:(201.12391) 2025-09-16 08:26:01.132 *** 2025-09-16 08:26:01.132 Process diagnostic dump for ORACLE.EXE (M002), OS id=25364, pid: 25, proc_ser: 132, sid: 201, sess_ser: 12391 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x7d92, blocks=0x1 wait_id=1689 seq_num=1690 snap_id=1 wait times: snap=0.004570 sec, exc=0.004570 sec, total=0.004570 sec wait times: max=infinite, heur=0.004570 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.000176 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x790a, blocks=0x1 wait_id=1688 seq_num=1689 snap_id=1 wait times: snap=0.000073 sec, exc=0.000073 sec, total=0.000073 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000426 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0xe9aa, blocks=0x1 wait_id=1687 seq_num=1688 snap_id=1 wait times: snap=0.005536 sec, exc=0.005536 sec, total=0.005536 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000359 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x80c2, blocks=0x1 wait_id=1686 seq_num=1687 snap_id=1 wait times: snap=0.003950 sec, exc=0.003950 sec, total=0.003950 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000395 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1f4a, blocks=0x1 wait_id=1685 seq_num=1686 snap_id=1 wait times: snap=0.004110 sec, exc=0.004110 sec, total=0.004110 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000140 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x80da, blocks=0x1 wait_id=1684 seq_num=1685 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.000144 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x8662, blocks=0x1 wait_id=1683 seq_num=1684 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000147 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0xecba, blocks=0x1 wait_id=1682 seq_num=1683 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.000396 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x7f6a, blocks=0x1 wait_id=1681 seq_num=1682 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.000136 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x810a, blocks=0x1 wait_id=1680 seq_num=1681 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.000142 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x7f5a, blocks=0x1 wait_id=1679 seq_num=1680 snap_id=1 wait times: snap=0.000056 sec, exc=0.000056 sec, total=0.000056 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000187 sec of elapsed time Sampled Session History of session 201 serial 12391 --------------------------------------------------- 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:26:00] not in wait at each sample [1 sample, 08:25:59] idle wait at each sample [session created at: 08:26:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-16 08:26:01.132 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 25364, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-16 08:12:01.177467 :CE1BC4E2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22560 osp_idx=23 osp_ver=301795 osp_pg=0 (spawn #597268) 2025-09-16 08:12:01.177467 :CE1BC4E3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:12:01.177468 :CE1BC4E4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=63 time=2395972471 2025-09-16 08:12:01.177468 :CE1BC4E5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:12:01.177468 :CE1BC4E6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:12:01.177469 :CE1BC4E7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:12:01.177469 :CE1BC4E8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:12:01.177469 :CE1BC4E9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:12:01.177958 :CE1BC4EA: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-09-16 08:12:31.245298 :CE1BCFC4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:13:01.250999 :CE1BD9A0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:13:01.240 2025-09-16 08:13:01.251002 :CE1BD9A1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22760 pso_num=25 pso_serial#=118 2025-09-16 08:13:01.251121 :CE1BD9A2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22760 osp_idx=23 osp_ver=301796 osp_pg=0 (spawn #597271) 2025-09-16 08:13:01.251122 :CE1BD9A3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:13:01.251122 :CE1BD9A4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=64 time=2396032549 2025-09-16 08:13:01.251123 :CE1BD9A5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:13:01.251123 :CE1BD9A6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:13:01.251123 :CE1BD9A7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:13:01.251124 :CE1BD9A8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:13:01.251124 :CE1BD9A9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:13:01.251675 :CE1BD9AA: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-09-16 08:13:31.377520 :CE1BE33F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:14:01.342377 :CE1BECB0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:14:01.331 2025-09-16 08:14:01.342379 :CE1BECB1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22576 pso_num=25 pso_serial#=119 2025-09-16 08:14:01.342514 :CE1BECB2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22576 osp_idx=23 osp_ver=301797 osp_pg=0 (spawn #597273) 2025-09-16 08:14:01.342515 :CE1BECB3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:14:01.342516 :CE1BECB4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=65 time=2396092643 2025-09-16 08:14:01.342516 :CE1BECB5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:14:01.342516 :CE1BECB6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:14:01.342516 :CE1BECB7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:14:01.342517 :CE1BECB8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:14:01.342517 :CE1BECB9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:14:01.342997 :CE1BECBA: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-09-16 08:14:31.413283 :CE1BF7C4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:15:01.438769 :CE1C01B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:15:01.427 2025-09-16 08:15:01.438771 :CE1C01B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21304 pso_num=25 pso_serial#=120 2025-09-16 08:15:01.438901 :CE1C01B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21304 osp_idx=23 osp_ver=301798 osp_pg=0 (spawn #597276) 2025-09-16 08:15:01.438902 :CE1C01B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:15:01.438903 :CE1C01BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=66 time=2396152737 2025-09-16 08:15:01.438903 :CE1C01BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:15:01.438904 :CE1C01BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:15:01.438904 :CE1C01BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:15:01.438904 :CE1C01BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:15:01.438905 :CE1C01BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:15:01.439386 :CE1C01C0: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-09-16 08:15:31.530658 :CE1C0B93:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:16:01.539548 :CE1C1533:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:16:01.528 2025-09-16 08:16:01.539550 :CE1C1534:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25068 pso_num=25 pso_serial#=121 2025-09-16 08:16:01.539694 :CE1C1535:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25068 osp_idx=23 osp_ver=301799 osp_pg=0 (spawn #597278) 2025-09-16 08:16:01.539695 :CE1C1536:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:16:01.539695 :CE1C1537:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=67 time=2396212830 2025-09-16 08:16:01.539695 :CE1C1538:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:16:01.539695 :CE1C1539:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:16:01.539696 :CE1C153A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:16:01.539696 :CE1C153B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:16:01.539697 :CE1C153C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:16:01.540183 :CE1C153D: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-09-16 08:16:31.642855 :CE1C1F02:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:17:01.609463 :CE1C292E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:17:01.608 2025-09-16 08:17:01.609465 :CE1C292F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25540 pso_num=25 pso_serial#=122 2025-09-16 08:17:01.609590 :CE1C2930:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25540 osp_idx=23 osp_ver=301800 osp_pg=0 (spawn #597280) 2025-09-16 08:17:01.609591 :CE1C2931:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:17:01.609592 :CE1C2932:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=68 time=2396272908 2025-09-16 08:17:01.609592 :CE1C2933:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:17:01.609593 :CE1C2934:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:17:01.609593 :CE1C2935:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:17:01.609593 :CE1C2936:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:17:01.609594 :CE1C2937:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:17:01.610099 :CE1C2938: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-09-16 08:17:31.746501 :CE1C36AD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:17:46.679677 :CE1C3B92:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:17:46.669 2025-09-16 08:17:46.679679 :CE1C3B93:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24088 pso_num=25 pso_serial#=123 2025-09-16 08:17:46.679809 :CE1C3B94:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24088 osp_idx=23 osp_ver=301801 osp_pg=0 (spawn #597283) 2025-09-16 08:17:46.679810 :CE1C3B95:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:17:46.679811 :CE1C3B96:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=69 time=2396317971 2025-09-16 08:17:46.679811 :CE1C3B97:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:17:46.679811 :CE1C3B98:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:17:46.679812 :CE1C3B99:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:17:46.679812 :CE1C3B9A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:17:46.679812 :CE1C3B9B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:17:46.680287 :CE1C3B9C: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-09-16 08:18:01.672686 :CE1C40B1: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-09-16 08:18:31.762219 :CE1C4AA7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:19:01.795087 :CE1C546B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:19:01.790 2025-09-16 08:19:01.795089 :CE1C546C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24464 pso_num=25 pso_serial#=124 2025-09-16 08:19:01.795219 :CE1C546D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24464 osp_idx=23 osp_ver=301802 osp_pg=0 (spawn #597285) 2025-09-16 08:19:01.795220 :CE1C546E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:19:01.795220 :CE1C546F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=70 time=2396393096 2025-09-16 08:19:01.795221 :CE1C5470:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:19:01.795221 :CE1C5471:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:19:01.795221 :CE1C5472:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:19:01.795222 :CE1C5473:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:19:01.795222 :CE1C5474:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:19:01.795727 :CE1C5475: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-09-16 08:19:31.865728 :CE1C5E71:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:20:01.891703 :CE1C6854:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:20:01.881 2025-09-16 08:20:01.891704 :CE1C6855:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25132 pso_num=25 pso_serial#=125 2025-09-16 08:20:01.891840 :CE1C6856:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25132 osp_idx=23 osp_ver=301803 osp_pg=0 (spawn #597287) 2025-09-16 08:20:01.891840 :CE1C6857:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:20:01.891841 :CE1C6858:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=71 time=2396453190 2025-09-16 08:20:01.891841 :CE1C6859:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:20:01.891841 :CE1C685A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:20:01.891842 :CE1C685B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:20:01.891842 :CE1C685C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:20:01.891842 :CE1C685D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:20:01.892363 :CE1C685E: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-09-16 08:20:31.981920 :CE1C722D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:21:01.962171 :CE1C7C08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:21:01.951 2025-09-16 08:21:01.962173 :CE1C7C09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24948 pso_num=25 pso_serial#=126 2025-09-16 08:21:01.962289 :CE1C7C0A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24948 osp_idx=23 osp_ver=301804 osp_pg=0 (spawn #597289) 2025-09-16 08:21:01.962289 :CE1C7C0B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:21:01.962290 :CE1C7C0C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=72 time=2396513268 2025-09-16 08:21:01.962290 :CE1C7C0D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:21:01.962291 :CE1C7C0E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:21:01.962291 :CE1C7C0F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:21:01.962291 :CE1C7C10:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:21:01.962291 :CE1C7C11:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:21:01.962759 :CE1C7C12: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-09-16 08:21:32.084422 :CE1C8604:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:22:02.066724 :CE1C8FA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:22:02.062 2025-09-16 08:22:02.066725 :CE1C8FAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25492 pso_num=25 pso_serial#=127 2025-09-16 08:22:02.066853 :CE1C8FAB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25492 osp_idx=23 osp_ver=301805 osp_pg=0 (spawn #597291) 2025-09-16 08:22:02.066854 :CE1C8FAC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:22:02.066854 :CE1C8FAD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=73 time=2396573362 2025-09-16 08:22:02.066855 :CE1C8FAE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:22:02.066855 :CE1C8FAF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:22:02.066855 :CE1C8FB0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:22:02.066856 :CE1C8FB1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:22:02.066856 :CE1C8FB2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:22:02.067314 :CE1C8FB3: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-09-16 08:22:32.159298 :CE1C9A53:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:23:02.168541 :CE1CA441:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:23:02.156 2025-09-16 08:23:02.168543 :CE1CA442:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25568 pso_num=25 pso_serial#=128 2025-09-16 08:23:02.168659 :CE1CA443:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25568 osp_idx=23 osp_ver=301806 osp_pg=0 (spawn #597294) 2025-09-16 08:23:02.168659 :CE1CA444:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:23:02.168660 :CE1CA445:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=74 time=2396633471 2025-09-16 08:23:02.168660 :CE1CA446:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:23:02.168660 :CE1CA447:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:23:02.168661 :CE1CA448:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:23:02.168661 :CE1CA449:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:23:02.168661 :CE1CA44A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:23:02.169228 :CE1CA44B: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-09-16 08:23:32.249835 :CE1CAE2A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:24:02.263997 :CE1CB7F9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:24:02.252 2025-09-16 08:24:02.263999 :CE1CB7FA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24564 pso_num=25 pso_serial#=129 2025-09-16 08:24:02.264128 :CE1CB7FB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24564 osp_idx=23 osp_ver=301807 osp_pg=0 (spawn #597296) 2025-09-16 08:24:02.264129 :CE1CB7FC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:24:02.264129 :CE1CB7FD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=75 time=2396693565 2025-09-16 08:24:02.264130 :CE1CB7FE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:24:02.264130 :CE1CB7FF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:24:02.264131 :CE1CB800:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:24:02.264131 :CE1CB801:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:24:02.264131 :CE1CB802:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:24:02.264651 :CE1CB803: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-09-16 08:24:32.333021 :CE1CC2EA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:25:02.357702 :CE1CCC99:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:25:02.346 2025-09-16 08:25:02.357704 :CE1CCC9A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24904 pso_num=25 pso_serial#=130 2025-09-16 08:25:02.357819 :CE1CCC9B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24904 osp_idx=23 osp_ver=301808 osp_pg=0 (spawn #597299) 2025-09-16 08:25:02.357820 :CE1CCC9C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:25:02.357820 :CE1CCC9D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=76 time=2396753658 2025-09-16 08:25:02.357821 :CE1CCC9E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:25:02.357821 :CE1CCC9F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:25:02.357822 :CE1CCCA0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:25:02.357822 :CE1CCCA1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:25:02.357822 :CE1CCCA2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:25:02.358403 :CE1CCCA3: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-09-16 08:25:32.476431 :CE1CD6A2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:25:57.789004 :CE1D1862:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:25:57.778 2025-09-16 08:25:57.789006 :CE1D1863:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23488 pso_num=25 pso_serial#=131 2025-09-16 08:25:57.789164 :CE1D1864:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23488 osp_idx=23 osp_ver=301809 osp_pg=0 (spawn #597301) 2025-09-16 08:25:57.789164 :CE1D1865:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:25:57.789165 :CE1D1866:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=77 time=2396809080 2025-09-16 08:25:57.789165 :CE1D1867:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:25:57.789165 :CE1D1868:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:25:57.789166 :CE1D1869:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:25:57.789166 :CE1D186A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:25:57.789166 :CE1D186B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:25:57.790013 :CE1D186C: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-09-16 08:25:57.790056 :CE1D1872: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-09-16 08:25:57.888163 :CE1D18A4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-16 08:26:00.222815 :CE1D1987:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-16 08:26:00.210 2025-09-16 08:26:00.222817 :CE1D1988:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25364 pso_num=25 pso_serial#=132 2025-09-16 08:26:00.222951 :CE1D1989:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25364 osp_idx=23 osp_ver=301810 osp_pg=0 (spawn #597303) 2025-09-16 08:26:00.222952 :CE1D198A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 08:26:00.222952 :CE1D198B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=78 time=2396811518 2025-09-16 08:26:00.222952 :CE1D198C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:26:00.222953 :CE1D198D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:26:00.222953 :CE1D198E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:26:00.222953 :CE1D198F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:26:00.222954 :CE1D1990:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:26:00.223468 :CE1D1991: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-09-16 08:26:00.223511 :CE1D1997: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: 25364, M002)