Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250610222337\orcl_m002_15704_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:36784M/63366M, Ph+PgF:43364M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15704, image: ORACLE.EXE (M002) *** 2025-06-10 22:23:37.631 *** SESSION ID:(193.25317) 2025-06-10 22:23:37.631 *** 2025-06-10 22:23:37.631 Process diagnostic dump for ORACLE.EXE (M002), OS id=15704, pid: 24, proc_ser: 244, sid: 193, sess_ser: 25317 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000000 sec ago Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000006 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=737 seq_num=738 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=736 seq_num=737 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=735 seq_num=736 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=734 seq_num=735 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=733 seq_num=734 snap_id=1 wait times: snap=0.000042 sec, exc=0.000042 sec, total=0.000042 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=732 seq_num=733 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=741 seq_num=742 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=740 seq_num=741 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=739 seq_num=740 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=738 seq_num=739 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time Sampled Session History of session 193 serial 25317 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [1 sample, 22:23:37] idle wait at each sample [session created at: 22:23:37] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-10 22:23:37.631 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15704, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-10 22:10:58.124653 :A2FE8A12:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15712 pso_num=24 pso_serial#=230 2025-06-10 22:10:58.124778 :A2FE8A13:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15712 osp_idx=22 osp_ver=383769 osp_pg=0 (spawn #272506) 2025-06-10 22:10:58.124779 :A2FE8A14:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:10:58.124779 :A2FE8A15:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=241 time=2569201313 2025-06-10 22:10:58.124780 :A2FE8A16:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:10:58.124780 :A2FE8A17:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:10:58.124780 :A2FE8A18:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:10:58.124780 :A2FE8A19:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:10:58.124781 :A2FE8A1A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:10:58.125271 :A2FE8A1B: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-06-10 22:11:28.179206 :A2FE93CE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:11:58.202249 :A2FE9D52:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:11:58.191 2025-06-10 22:11:58.202251 :A2FE9D53:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13748 pso_num=24 pso_serial#=231 2025-06-10 22:11:58.202386 :A2FE9D54:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13748 osp_idx=22 osp_ver=383770 osp_pg=0 (spawn #272508) 2025-06-10 22:11:58.202387 :A2FE9D55:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:11:58.202387 :A2FE9D56:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=242 time=2569261391 2025-06-10 22:11:58.202387 :A2FE9D57:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:11:58.202388 :A2FE9D58:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:11:58.202388 :A2FE9D59:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:11:58.202389 :A2FE9D5A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:11:58.202389 :A2FE9D5B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:11:58.202885 :A2FE9D5C: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-06-10 22:12:28.268667 :A2FEA782:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:12:58.279879 :A2FEB0EE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:12:58.269 2025-06-10 22:12:58.279882 :A2FEB0EF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15704 pso_num=24 pso_serial#=232 2025-06-10 22:12:58.280023 :A2FEB0F0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15704 osp_idx=22 osp_ver=383771 osp_pg=0 (spawn #272511) 2025-06-10 22:12:58.280024 :A2FEB0F1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:12:58.280024 :A2FEB0F2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=243 time=2569321469 2025-06-10 22:12:58.280025 :A2FEB0F3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:12:58.280026 :A2FEB0F4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:12:58.280026 :A2FEB0F5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:12:58.280026 :A2FEB0F6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:12:58.280026 :A2FEB0F7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:12:58.280489 :A2FEB0F8: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-06-10 22:13:28.335293 :A2FEBA7A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:13:58.366897 :A2FEC451:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:13:58.356 2025-06-10 22:13:58.366899 :A2FEC452:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10948 pso_num=24 pso_serial#=233 2025-06-10 22:13:58.367037 :A2FEC453:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10948 osp_idx=22 osp_ver=383772 osp_pg=0 (spawn #272513) 2025-06-10 22:13:58.367037 :A2FEC454:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:13:58.367038 :A2FEC455:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=244 time=2569381547 2025-06-10 22:13:58.367038 :A2FEC456:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:13:58.367039 :A2FEC457:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:13:58.367039 :A2FEC458:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:13:58.367039 :A2FEC459:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:13:58.367040 :A2FEC45A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:13:58.367514 :A2FEC45B: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-06-10 22:14:28.445420 :A2FECDBC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:14:58.450348 :A2FED78C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:14:58.439 2025-06-10 22:14:58.450350 :A2FED78D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13976 pso_num=24 pso_serial#=234 2025-06-10 22:14:58.450478 :A2FED78E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13976 osp_idx=22 osp_ver=383773 osp_pg=0 (spawn #272516) 2025-06-10 22:14:58.450478 :A2FED78F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:14:58.450478 :A2FED790:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=245 time=2569441641 2025-06-10 22:14:58.450479 :A2FED791:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:14:58.450479 :A2FED792:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:14:58.450479 :A2FED793:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:14:58.450480 :A2FED794:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:14:58.450480 :A2FED795:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:14:58.450975 :A2FED796: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-06-10 22:15:28.500894 :A2FEE13F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:15:58.498023 :A2FEEAA7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:15:58.486 2025-06-10 22:15:58.498026 :A2FEEAA8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16860 pso_num=24 pso_serial#=235 2025-06-10 22:15:58.498140 :A2FEEAA9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16860 osp_idx=22 osp_ver=383774 osp_pg=0 (spawn #272518) 2025-06-10 22:15:58.498141 :A2FEEAAA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:15:58.498141 :A2FEEAAB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=246 time=2569501688 2025-06-10 22:15:58.498142 :A2FEEAAC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:15:58.498142 :A2FEEAAD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:15:58.498142 :A2FEEAAE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:15:58.498143 :A2FEEAAF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:15:58.498143 :A2FEEAB0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:15:58.498636 :A2FEEAB1: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-06-10 22:16:28.571091 :A2FEF420:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:16:58.568593 :A2FEFD97:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:16:58.558 2025-06-10 22:16:58.568595 :A2FEFD98:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14672 pso_num=24 pso_serial#=236 2025-06-10 22:16:58.568732 :A2FEFD99:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14672 osp_idx=22 osp_ver=383775 osp_pg=0 (spawn #272520) 2025-06-10 22:16:58.568732 :A2FEFD9A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:16:58.568733 :A2FEFD9B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=247 time=2569561750 2025-06-10 22:16:58.568733 :A2FEFD9C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:16:58.568734 :A2FEFD9D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:16:58.568734 :A2FEFD9E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:16:58.568734 :A2FEFD9F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:16:58.568735 :A2FEFDA0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:16:58.569209 :A2FEFDA1: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-06-10 22:17:28.639125 :A2FF080C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:17:58.681988 :A2FF11BA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:17:58.671 2025-06-10 22:17:58.681990 :A2FF11BB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12136 pso_num=24 pso_serial#=237 2025-06-10 22:17:58.682131 :A2FF11BC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12136 osp_idx=22 osp_ver=383776 osp_pg=0 (spawn #272522) 2025-06-10 22:17:58.682132 :A2FF11BD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:17:58.682133 :A2FF11BE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=248 time=2569621860 2025-06-10 22:17:58.682133 :A2FF11BF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:17:58.682134 :A2FF11C0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:17:58.682134 :A2FF11C1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:17:58.682134 :A2FF11C2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:17:58.682135 :A2FF11C3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:17:58.682617 :A2FF11C4: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-06-10 22:18:28.769158 :A2FF1AFE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:18:58.785018 :A2FF245C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:18:58.773 2025-06-10 22:18:58.785019 :A2FF245D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8928 pso_num=24 pso_serial#=238 2025-06-10 22:18:58.785152 :A2FF245E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8928 osp_idx=22 osp_ver=383777 osp_pg=0 (spawn #272524) 2025-06-10 22:18:58.785152 :A2FF245F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:18:58.785153 :A2FF2460:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=249 time=2569681969 2025-06-10 22:18:58.785153 :A2FF2461:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:18:58.785154 :A2FF2462:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:18:58.785154 :A2FF2463:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:18:58.785155 :A2FF2464:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:18:58.785155 :A2FF2465:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:18:58.785664 :A2FF2466: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-06-10 22:19:28.869005 :A2FF2E02:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:19:58.876862 :A2FF379E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:19:58.865 2025-06-10 22:19:58.876865 :A2FF379F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16960 pso_num=24 pso_serial#=239 2025-06-10 22:19:58.876989 :A2FF37A0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16960 osp_idx=22 osp_ver=383778 osp_pg=0 (spawn #272527) 2025-06-10 22:19:58.876990 :A2FF37A1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:19:58.876990 :A2FF37A2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=250 time=2569742063 2025-06-10 22:19:58.876990 :A2FF37A3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:19:58.876991 :A2FF37A4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:19:58.876991 :A2FF37A5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:19:58.876991 :A2FF37A6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:19:58.876992 :A2FF37A7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:19:58.877475 :A2FF37A8: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-06-10 22:20:23.908864 :A2FF4057: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-06-10 22:20:23.908901*:A2FF4059:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002474BB58 estart=1749565222 eid=16903277 ctx=0x000000077BD98A08 cwh=0x00000000246801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-10 22:20:23.908901*:A2FF405A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002474BB58 curCtx=0x000000002468F708 pubTabIdxCnt=0 2025-06-10 22:20:23.908901*:A2FF405B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002474BB58 2025-06-10 22:20:23.908901*:A2FF405C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002474BB58 2025-06-10 22:20:23.908901*:A2FF405D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002474BB58 curCtx=0x000000002468F708 xsc->flg4=65792 flags=2 2025-06-10 22:20:23.908901*:A2FF405E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002474BB58 2025-06-10 22:20:23.909592 :A2FF405F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 22:20:23.915048 :A2FF4067:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 22:20:23.916009 :A2FF406F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 22:20:23.916157 :A2FF4078:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 22:20:54.011914 :A2FF4C71:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:20:59.000269 :A2FF4E4A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:20:58.996 2025-06-10 22:20:59.000271 :A2FF4E4B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17336 pso_num=24 pso_serial#=240 2025-06-10 22:20:59.000388 :A2FF4E4C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17336 osp_idx=22 osp_ver=383779 osp_pg=0 (spawn #272529) 2025-06-10 22:20:59.000389 :A2FF4E4D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:20:59.000389 :A2FF4E4E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=251 time=2569802172 2025-06-10 22:20:59.000390 :A2FF4E4F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:20:59.000390 :A2FF4E50:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:20:59.000390 :A2FF4E51:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:20:59.000390 :A2FF4E52:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:20:59.000391 :A2FF4E53:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:20:59.000913 :A2FF4E54: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-06-10 22:21:29.054276 :A2FF57D1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:21:59.101763 :A2FF612E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:21:59.090 2025-06-10 22:21:59.101765 :A2FF612F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11908 pso_num=24 pso_serial#=241 2025-06-10 22:21:59.101881 :A2FF6130:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11908 osp_idx=22 osp_ver=383780 osp_pg=0 (spawn #272531) 2025-06-10 22:21:59.101881 :A2FF6131:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:21:59.101882 :A2FF6132:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=252 time=2569862282 2025-06-10 22:21:59.101883 :A2FF6133:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:21:59.101883 :A2FF6134:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:21:59.101883 :A2FF6135:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:21:59.101884 :A2FF6136:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:21:59.101884 :A2FF6137:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:21:59.102378 :A2FF6138: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-06-10 22:22:29.198378 :A2FF6B6F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:22:59.211884 :A2FF74CB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:22:59.200 2025-06-10 22:22:59.211886 :A2FF74CC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11644 pso_num=24 pso_serial#=242 2025-06-10 22:22:59.212010 :A2FF74CD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11644 osp_idx=22 osp_ver=383781 osp_pg=0 (spawn #272534) 2025-06-10 22:22:59.212011 :A2FF74CE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:22:59.212011 :A2FF74CF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=253 time=2569922391 2025-06-10 22:22:59.212012 :A2FF74D0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:22:59.212012 :A2FF74D1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:22:59.212013 :A2FF74D2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:22:59.212013 :A2FF74D3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:22:59.212013 :A2FF74D4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:22:59.212486 :A2FF74D5: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-06-10 22:23:29.293941 :A2FF7E3B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:23:36.236387 :A2FFBBEA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:23:36.235 2025-06-10 22:23:36.236389 :A2FFBBEB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14600 pso_num=24 pso_serial#=243 2025-06-10 22:23:36.236580 :A2FFBBEC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14600 osp_idx=22 osp_ver=383782 osp_pg=0 (spawn #272536) 2025-06-10 22:23:36.236581 :A2FFBBED:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:23:36.236581 :A2FFBBEE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=254 time=2569959422 2025-06-10 22:23:36.236581 :A2FFBBEF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:23:36.236582 :A2FFBBF0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:23:36.236582 :A2FFBBF1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:23:36.236582 :A2FFBBF2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:23:36.236583 :A2FFBBF3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:23:36.237463 :A2FFBBF4: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-06-10 22:23:36.237515 :A2FFBBFA: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-06-10 22:23:36.344900 :A2FFBC2C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-10 22:23:37.548908 :A2FFBC5F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-10 22:23:37.537 2025-06-10 22:23:37.548911 :A2FFBC60:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15704 pso_num=24 pso_serial#=244 2025-06-10 22:23:37.549050 :A2FFBC61:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15704 osp_idx=22 osp_ver=383783 osp_pg=0 (spawn #272538) 2025-06-10 22:23:37.549051 :A2FFBC62:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:23:37.549051 :A2FFBC63:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=255 time=2569960735 2025-06-10 22:23:37.549051 :A2FFBC64:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:23:37.549052 :A2FFBC65:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:23:37.549052 :A2FFBC66:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:23:37.549052 :A2FFBC67:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:23:37.549053 :A2FFBC68:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:23:37.549563 :A2FFBC69: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-06-10 22:23:37.549610 :A2FFBC6F: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: 15704, M002)