Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250905220007\orcl_j001_24248_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250905220007\orcl_j001_24248_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:34992M/63366M, Ph+PgF:39800M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 24248, image: ORACLE.EXE (J001) *** 2025-09-05 22:00:07.328 *** SESSION ID:(201.34265) 2025-09-05 22:00:07.328 *** 2025-09-05 22:00:07.328 Process diagnostic dump for ORACLE.EXE (J001), OS id=24248, pid: 25, proc_ser: 160, sid: 201, sess_ser: 34265 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24248 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.317786 sec, exc=0.317786 sec, total=0.317786 sec wait times: max=0.500000 sec, heur=7.025273 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.502090 sec, exc=0.502090 sec, total=0.502090 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499188 sec, exc=0.499188 sec, total=0.499188 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.110183 sec, exc=0.110183 sec, total=0.110183 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.501122 sec, exc=0.501122 sec, total=0.501122 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time Sampled Session History of session 201 serial 34265 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:00 - 22:00:07] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-05 22:00:07.328 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 24248, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-05 21:49:29.501200 :C97D3F7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24444 pso_num=25 pso_serial#=150 2025-09-05 21:49:29.501314 :C97D3F7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24444 osp_idx=23 osp_ver=282193 osp_pg=0 (spawn #562457) 2025-09-05 21:49:29.501315 :C97D3F7E: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-05 21:49:29.501315 :C97D3F7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=1494620799 2025-09-05 21:49:29.501316 :C97D3F80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:49:29.501317 :C97D3F81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:49:29.501317 :C97D3F82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:49:29.501317 :C97D3F83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:49:29.501318 :C97D3F84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:49:29.501807 :C97D3F85: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-05 21:49:59.590165 :C97D4985:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:50:29.597714 :C97D5367:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:50:29.585 2025-09-05 21:50:29.597717 :C97D5368:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24244 pso_num=25 pso_serial#=151 2025-09-05 21:50:29.597836 :C97D5369:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24244 osp_idx=23 osp_ver=282194 osp_pg=0 (spawn #562459) 2025-09-05 21:50:29.597836 :C97D536A: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-05 21:50:29.597837 :C97D536B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=1494680893 2025-09-05 21:50:29.597837 :C97D536C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:50:29.597838 :C97D536D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:50:29.597839 :C97D536E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:50:29.597839 :C97D536F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:50:29.597839 :C97D5370:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:50:29.598492 :C97D5371: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-05 21:50:59.648927 :C97D5E77:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:51:29.661790 :C97D6828:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:51:29.649 2025-09-05 21:51:29.661792 :C97D6829:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23780 pso_num=25 pso_serial#=152 2025-09-05 21:51:29.661956 :C97D682A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23780 osp_idx=23 osp_ver=282195 osp_pg=0 (spawn #562461) 2025-09-05 21:51:29.661957 :C97D682B: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-05 21:51:29.661957 :C97D682C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=10 time=1494740955 2025-09-05 21:51:29.661958 :C97D682D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:51:29.661958 :C97D682E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:51:29.661959 :C97D682F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:51:29.661959 :C97D6830:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:51:29.661960 :C97D6831:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:51:29.662526 :C97D6832: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-05 21:51:59.746677 :C97D7248:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:52:29.733667 :C97D7C45:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:52:29.722 2025-09-05 21:52:29.733669 :C97D7C46:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23584 pso_num=25 pso_serial#=153 2025-09-05 21:52:29.733787 :C97D7C47:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23584 osp_idx=23 osp_ver=282196 osp_pg=0 (spawn #562464) 2025-09-05 21:52:29.733787 :C97D7C48: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-05 21:52:29.733788 :C97D7C49:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=11 time=1494801033 2025-09-05 21:52:29.733788 :C97D7C4A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:52:29.733788 :C97D7C4B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:52:29.733788 :C97D7C4C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:52:29.733789 :C97D7C4D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:52:29.733789 :C97D7C4E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:52:29.734292 :C97D7C4F: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-05 21:52:59.834900 :C97D862E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:53:29.836708 :C97D9019:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:53:29.825 2025-09-05 21:53:29.836710 :C97D901A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23560 pso_num=25 pso_serial#=154 2025-09-05 21:53:29.836830 :C97D901B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23560 osp_idx=23 osp_ver=282197 osp_pg=0 (spawn #562466) 2025-09-05 21:53:29.836830 :C97D901C: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-05 21:53:29.836831 :C97D901D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=12 time=1494861127 2025-09-05 21:53:29.836831 :C97D901E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:53:29.836832 :C97D901F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:53:29.836833 :C97D9020:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:53:29.836833 :C97D9021:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:53:29.836833 :C97D9022:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:53:29.837355 :C97D9023: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-05 21:53:59.908098 :C97D9A4E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:54:29.918867 :C97DA417:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:54:29.907 2025-09-05 21:54:29.918869 :C97DA418:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24108 pso_num=25 pso_serial#=155 2025-09-05 21:54:29.918982 :C97DA419:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24108 osp_idx=23 osp_ver=282198 osp_pg=0 (spawn #562469) 2025-09-05 21:54:29.918983 :C97DA41A: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-05 21:54:29.918983 :C97DA41B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=1494921221 2025-09-05 21:54:29.918983 :C97DA41C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:54:29.918984 :C97DA41D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:54:29.918984 :C97DA41E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:54:29.918984 :C97DA41F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:54:29.918985 :C97DA420:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:54:29.919520 :C97DA421: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-05 21:54:59.979888 :C97DADFF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:55:29.971547 :C97DB7BD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:55:29.960 2025-09-05 21:55:29.971549 :C97DB7BE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17016 pso_num=25 pso_serial#=156 2025-09-05 21:55:29.971669 :C97DB7BF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17016 osp_idx=23 osp_ver=282199 osp_pg=0 (spawn #562471) 2025-09-05 21:55:29.971670 :C97DB7C0: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-05 21:55:29.971670 :C97DB7C1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=1494981268 2025-09-05 21:55:29.971671 :C97DB7C2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:55:29.971671 :C97DB7C3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:55:29.971671 :C97DB7C4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:55:29.971671 :C97DB7C5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:55:29.971671 :C97DB7C6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:55:29.972183 :C97DB7C7: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-05 21:56:00.039975 :C97DC2AE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:56:15.029766 :C97DC781:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:56:15.017 2025-09-05 21:56:15.029768 :C97DC782:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20324 pso_num=25 pso_serial#=157 2025-09-05 21:56:15.029937 :C97DC783:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20324 osp_idx=23 osp_ver=282200 osp_pg=0 (spawn #562473) 2025-09-05 21:56:15.029938 :C97DC784: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-05 21:56:15.029938 :C97DC785:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=1495026330 2025-09-05 21:56:15.029939 :C97DC786:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:56:15.029939 :C97DC787:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:56:15.029941 :C97DC788:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:56:15.029941 :C97DC789:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:56:15.029941 :C97DC78A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:56:15.030478 :C97DC78B: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-05 21:56:30.025423 :C97DCCC3: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-05 21:56:30.025455*:C97DCCC4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1757080589 eid=17041288 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-05 21:56:30.025455*:C97DCCC5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-09-05 21:56:30.025455*:C97DCCC6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-09-05 21:56:30.025455*:C97DCCC7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-09-05 21:56:30.025455*:C97DCCC8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-09-05 21:56:30.025455*:C97DCCC9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-09-05 21:56:30.026080 :C97DCCCA: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-09-05 21:56:30.037318 :C97DCCD2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-05 21:56:30.037828 :C97DCCD3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-05 21:56:30.040165 :C97DCCD4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-05 21:56:30.040528 :C97DCCD5: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-09-05 21:56:30.040561 :C97DCCD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-05 21:56:30.041422 :C97DCCDE: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-09-05 21:56:30.041570 :C97DCCE6: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-09-05 21:57:00.123203 :C97DD6AD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:57:30.094728 :C97DE063:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:57:30.083 2025-09-05 21:57:30.094730 :C97DE064:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19812 pso_num=25 pso_serial#=158 2025-09-05 21:57:30.094857 :C97DE065:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19812 osp_idx=23 osp_ver=282201 osp_pg=0 (spawn #562475) 2025-09-05 21:57:30.094858 :C97DE066: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-05 21:57:30.094858 :C97DE067:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=1495101393 2025-09-05 21:57:30.094859 :C97DE068:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:57:30.094859 :C97DE069:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:57:30.094860 :C97DE06A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:57:30.094860 :C97DE06B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:57:30.094860 :C97DE06C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:57:30.095355 :C97DE06D: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-05 21:58:00.156861 :C97DEA40:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:58:30.164273 :C97DF422:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:58:30.153 2025-09-05 21:58:30.164275 :C97DF423:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22456 pso_num=25 pso_serial#=159 2025-09-05 21:58:30.164411 :C97DF424:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22456 osp_idx=23 osp_ver=282202 osp_pg=0 (spawn #562477) 2025-09-05 21:58:30.164412 :C97DF425: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-05 21:58:30.164412 :C97DF426:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=17 time=1495161455 2025-09-05 21:58:30.164413 :C97DF427:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:58:30.164414 :C97DF428:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:58:30.164414 :C97DF429:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:58:30.164414 :C97DF42A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:58:30.164415 :C97DF42B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:58:30.164911 :C97DF42C: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-05 21:59:00.194135 :C97DFE2A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-05 21:59:30.240971 :C97E07FF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-05 21:59:30.229 2025-09-05 21:59:30.240973 :C97E0800:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24248 pso_num=25 pso_serial#=160 2025-09-05 21:59:30.241089 :C97E0801:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24248 osp_idx=23 osp_ver=282203 osp_pg=0 (spawn #562479) 2025-09-05 21:59:30.241089 :C97E0802: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-05 21:59:30.241090 :C97E0803:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=18 time=1495221533 2025-09-05 21:59:30.241091 :C97E0804:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:59:30.241091 :C97E0805:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:59:30.241091 :C97E0806:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:59:30.241092 :C97E0807:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:59:30.241092 :C97E0808:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:59:30.241598 :C97E0809: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-05 22:00:00.278204 :C97E1300: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-05 22:00:00.278204*:C97E132F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB898 estart=1757080799 eid=16787436 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-05 22:00:00.278204*:C97E1331:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 pubTabIdxCnt=0 2025-09-05 22:00:00.278204*:C97E1332:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB898 2025-09-05 22:00:00.278204*:C97E1333:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB898 2025-09-05 22:00:00.278204*:C97E1341:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 xsc->flg4=65792 flags=2 2025-09-05 22:00:00.278204*:C97E1342:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB898 2025-09-05 22:00:00.285153 :C97E1343: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-09-05 22:00:00.285205 :C97E1346:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-05 22:00:00.290531 :C97E1374:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-05 22:00:00.291123 :C97E1375:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 32ad120000000000 2025-09-05 22:00:00.292745 :C97E1376:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.292918 :C97E1389:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.292930 :C97E138A:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-09-05 22:00:00.292948 :C97E138B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.293113 :C97E1394:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.293825 :C97E13A3:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 236625241) 2025-09-05 22:00:00.293830 :C97E13A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.293861 :C97E13AF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.293868 :C97E13B8:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65561 1) 2025-09-05 22:00:00.293871 :C97E13BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.293894 :C97E13C8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.293897 :C97E13D1:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65561) 2025-09-05 22:00:00.293900 :C97E13D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.293951 :C97E13EC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.294700 :C97E148F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.294722 :C97E14A4:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-05 22:00:00.295661 :C97E14C8:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 33ad120000000000 2025-09-05 22:00:00.296047 :C97E14DE:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 236625241) 2025-09-05 22:00:00.296055 :C97E14DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.296083 :C97E14EA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 22:00:00.304813 :C97E1536:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-05 22:00:00.306134 :C97E153F:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch: cache buffers chains] 33092852632/0x7b47cdb98 150/0x96 0/0x0 wait_id=237 seq_num=238 snap_id=1 2025-09-05 22:00:00.306147 :C97E1540:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch: cache buffers chains] 33092852632/0x7b47cdb98 150/0x96 0/0x0 wait_id=237 seq_num=238 snap_id=1 2025-09-05 22:00:00.306148 :C97E1541:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=13, exc=13, tot=13 2025-09-05 22:00:00.306635 :C97E155A: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-09-05 22:00:00.306682 :C97E155D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-05 22:00:00.402799 :C97E1653: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-05 22:00:04.013634 :C97E1B2E: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 24248, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de