Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250523220006\orcl_j003_13348_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250523220006\orcl_j003_13348_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:37320M/63366M, Ph+PgF:44372M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 13348, image: ORACLE.EXE (J003) *** 2025-05-23 22:00:06.256 *** SESSION ID:(233.22991) 2025-05-23 22:00:06.256 *** 2025-05-23 22:00:06.256 Process diagnostic dump for ORACLE.EXE (J003), OS id=13348, pid: 29, proc_ser: 247, sid: 233, sess_ser: 22991 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13348 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12473, hash value=3368949516 Current Wait Stack: Not in wait; last wait ended 2.399938 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.399965 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000153 sec, exc=0.000153 sec, total=0.000153 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.065721 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000009 sec, exc=0.000009 sec, total=0.000009 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.764367 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025445 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012788 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000078 sec, exc=0.000078 sec, total=0.000078 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049096 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000107 sec, exc=0.000107 sec, total=0.000107 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007934 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.000114 sec, exc=0.000114 sec, total=0.000114 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262826 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.007364 sec, exc=0.007364 sec, total=0.007364 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000177 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000115 sec, exc=0.000115 sec, total=0.000115 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007781 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000055 sec of elapsed time Sampled Session History of session 233 serial 22991 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 22:00:02 - 22:00:05] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-23 22:00:06.256 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 13348, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-23 01:00:19.088238 :9AB8A1E5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 01:00:26.251195 :9AB8A424:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 01:00:26.243 2025-05-23 01:00:26.251197 :9AB8A425:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9508 pso_num=29 pso_serial#=240 2025-05-23 01:00:26.251339 :9AB8A426:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9508 (legacy spawn) 2025-05-23 01:00:26.257888 :9AB8A427:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 01:00:26.258072 :9AB8A42C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 01:00:26.258898 :9AB8A431:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 01:00:30.926563 :9AB8A587:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 01:00:30.917 2025-05-23 01:00:30.926565 :9AB8A588:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12820 pso_num=29 pso_serial#=241 2025-05-23 01:00:30.926698 :9AB8A589:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12820 (legacy spawn) 2025-05-23 01:00:30.933135 :9AB8A58A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 01:00:30.933302 :9AB8A592:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 01:00:30.934150 :9AB8A594:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 01:00:58.280036 :9AB8AFF8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 01:00:58.269 2025-05-23 01:00:58.280037 :9AB8AFF9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13016 pso_num=29 pso_serial#=242 2025-05-23 01:00:58.280179 :9AB8AFFA:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13016 osp_idx=27 osp_ver=11461 osp_pg=0 (spawn #209602) 2025-05-23 01:00:58.280180 :9AB8AFFB:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-23 01:00:58.280180 :9AB8AFFC:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=234 time=937833172 2025-05-23 01:00:58.280181 :9AB8AFFD:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-23 01:00:58.280181 :9AB8AFFE:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-23 01:00:58.280182 :9AB8AFFF:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-23 01:00:58.280183 :9AB8B000:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-23 01:00:58.280183 :9AB8B001:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-23 01:00:58.280673 :9AB8B002:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-23 01:00:58.280715 :9AB8B008:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 01:00:58.288892 :9AB8B01A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 01:00:59.660503 :9AB8B0E5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 01:00:59.654 2025-05-23 01:00:59.660505 :9AB8B0E6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13896 pso_num=29 pso_serial#=243 2025-05-23 01:00:59.660637 :9AB8B0E7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13896 osp_idx=27 osp_ver=11462 osp_pg=0 (spawn #209603) 2025-05-23 01:00:59.660638 :9AB8B0E8:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-23 01:00:59.660638 :9AB8B0E9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=235 time=937834563 2025-05-23 01:00:59.660639 :9AB8B0EA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-23 01:00:59.660639 :9AB8B0EB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-23 01:00:59.660640 :9AB8B0EC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-23 01:00:59.660640 :9AB8B0ED:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-23 01:00:59.660640 :9AB8B0EE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-23 01:00:59.661229 :9AB8B0EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-23 01:00:59.661272 :9AB8B0F5:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 01:00:59.868389 :9AB8B119:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 01:00:59.869318 :9AB8B121:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 01:00:59.869584 :9AB8B129:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 01:00:59.869799 :9AB8B131:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 01:00:59.871505 :9AB8B139:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 01:05:58.968409 :9AB91290:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 01:05:58.956 2025-05-23 01:05:58.968412 :9AB91291:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11740 pso_num=29 pso_serial#=244 2025-05-23 01:05:58.968549 :9AB91292:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11740 osp_idx=27 osp_ver=11463 osp_pg=0 (spawn #209616) 2025-05-23 01:05:58.968549 :9AB91293:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-23 01:05:58.968550 :9AB91294:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=236 time=938133860 2025-05-23 01:05:58.968550 :9AB91295:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-23 01:05:58.968551 :9AB91296:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-23 01:05:58.968551 :9AB91297:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-23 01:05:58.968551 :9AB91298:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-23 01:05:58.968552 :9AB91299:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-23 01:05:58.969084 :9AB9129A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-23 01:05:58.969124 :9AB912A0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 01:05:58.973116 :9AB912D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:05:58.973155 :9AB912D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:05:58.973406 :9AB912D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:05:58.973422 :9AB912D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:05:58.973636 :9AB912D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:05:58.973649 :9AB912DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:05:58.973853 :9AB912DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:05:58.973866 :9AB912DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:05:58.974070 :9AB912DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:05:58.974083 :9AB912DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:05:58.974280 :9AB912DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:05:58.974292 :9AB912E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:05:58.976630 :9AB912E1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 01:36:02.953635 :9ABB660A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 01:36:02.941 2025-05-23 01:36:02.953637 :9ABB660B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12328 pso_num=29 pso_serial#=245 2025-05-23 01:36:02.953780 :9ABB660C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12328 osp_idx=27 osp_ver=11464 osp_pg=0 (spawn #209683) 2025-05-23 01:36:02.953780 :9ABB660D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-23 01:36:02.953781 :9ABB660E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=237 time=939937844 2025-05-23 01:36:02.953781 :9ABB660F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-23 01:36:02.953781 :9ABB6610:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-23 01:36:02.953782 :9ABB6611:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-23 01:36:02.953782 :9ABB6612:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-23 01:36:02.953782 :9ABB6613:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-23 01:36:02.954297 :9ABB6614:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-23 01:36:02.954347 :9ABB661A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 01:36:02.958502 :9ABB6633:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:36:02.958548 :9ABB6634:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:36:02.958805 :9ABB6635:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:36:02.958821 :9ABB6636:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:36:02.959038 :9ABB6637:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:36:02.959053 :9ABB6638:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:36:02.959259 :9ABB6639:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:36:02.959272 :9ABB663A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:36:02.959477 :9ABB663B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:36:02.959489 :9ABB663C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:36:02.959695 :9ABB663D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-23 01:36:02.959707 :9ABB663E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-23 01:36:02.962117 :9ABB663F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 16:00:03.300800 :9AFD8AC4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 16:00:03.293 2025-05-23 16:00:03.300802 :9AFD8AC5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13024 pso_num=29 pso_serial#=246 2025-05-23 16:00:03.300930 :9AFD8AC6:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13024 osp_idx=27 osp_ver=11465 osp_pg=0 (spawn #211678) 2025-05-23 16:00:03.300931 :9AFD8AC7:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-23 16:00:03.300931 :9AFD8AC8:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=238 time=991778188 2025-05-23 16:00:03.300932 :9AFD8AC9:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-23 16:00:03.300932 :9AFD8ACA:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-23 16:00:03.300933 :9AFD8ACB:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-23 16:00:03.300933 :9AFD8ACC:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-23 16:00:03.300934 :9AFD8ACD:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-23 16:00:03.301438 :9AFD8ACE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.301480 :9AFD8AD4:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-05-23 16:00:03.309057 :9AFD8AE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.310109 :9AFD8AEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.310748 :9AFD8AF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.311220 :9AFD8AFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.311659 :9AFD8B02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.314037 :9AFD8B0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.315186 :9AFD8B12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.363645 :9AFD8B1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.364595 :9AFD8B22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.364837 :9AFD8B2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.365062 :9AFD8B32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 16:00:03.366766 :9AFD8B3A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-23 22:00:02.070455 :9B193BAA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-23 22:00:02.063 2025-05-23 22:00:02.070456 :9B193BAB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13348 pso_num=29 pso_serial#=247 2025-05-23 22:00:02.070577 :9B193BB1:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13348 osp_idx=27 osp_ver=11466 osp_pg=0 (spawn #212503) 2025-05-23 22:00:02.070578 :9B193BB2:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-23 22:00:02.070578 :9B193BB3:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=239 time=1013374329 2025-05-23 22:00:02.070579 :9B193BB4:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-23 22:00:02.070579 :9B193BB5:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-23 22:00:02.070579 :9B193BB6:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-23 22:00:02.070580 :9B193BB7:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-23 22:00:02.070580 :9B193BB8:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-23 22:00:02.071064 :9B193BB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.076209 :9B193BE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.076251 :9B193BE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-23 22:00:02.094323 :9B193DB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.094558 :9B193DBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.094743 :9B193DC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.095054 :9B193DCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.095272 :9B193DD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.095446 :9B193DDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.095619 :9B193DE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.095787 :9B193DEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.095952 :9B193DF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.096110 :9B193DFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.096263 :9B193E03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.096417 :9B193E0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.096571 :9B193E13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.096723 :9B193E1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.097335 :9B193E23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.112515 :9B193E2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=16 loc='ktm.h LINE:583 ID:ktmpsm' id1=0 id2=0 name= type=0 2025-05-23 22:00:02.125479 :9B193E91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.292452*:9B19418F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000314B3AD0 estart=1748008803 eid=18658691 ctx=0x0000000777968158 cwh=0x000000003E7F0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-23 22:00:03.292452*:9B194190:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000314B3AD0 curCtx=0x000000003E6F1BF8 pubTabIdxCnt=0 2025-05-23 22:00:03.292452*:9B194191:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000314B3AD0 2025-05-23 22:00:03.292452*:9B194192:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000314B3AD0 2025-05-23 22:00:03.292452*:9B194193:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003152B6F0 estart=1748008802 eid=16777340 ctx=0x000000077B54EAC8 cwh=0x0000000031520068 cpu=968750 buffg=56014 interb=196608 r=24 rb=196608 w=0 wb=0 2025-05-23 22:00:03.292452*:9B194194:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003152B6F0 curCtx=0x000000003E6E7FD8 pubTabIdxCnt=0 2025-05-23 22:00:03.292452*:9B194195:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003152B6F0 2025-05-23 22:00:03.292452*:9B194196:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003152B6F0 2025-05-23 22:00:03.292452*:9B194197:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000314B3AD0 curCtx=0x000000003E6F1BF8 xsc->flg4=65792 flags=1 2025-05-23 22:00:03.292452*:9B194198:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000314B3AD0 2025-05-23 22:00:03.753046 :9B194231:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.771166 :9B194260:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 9, ext 0, nex 3 2025-05-23 22:00:03.771176 :9B194261:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00818fe5 where: kduwh01: kdusru 2025-05-23 22:00:03.771176*:9B194264:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003152B6F0 curCtx=0x000000003E6E7FD8 xsc->flg4=65792 flags=2 2025-05-23 22:00:03.771176*:9B194265:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003152B6F0 2025-05-23 22:00:03.776530 :9B194266:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.799358 :9B19429F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.801529 :9B1942A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-23 22:00:03.801749 :9B1942A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-23 22:00:03.802744 :9B1942A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.802898 :9B1942AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.803969 :9B1942BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.805730 :9B1942C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.806051 :9B1942CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-23 22:00:03.870574*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-23 22:00:03.938655 :9B19436F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:05.808480 :9B1944A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-23 22:00:05.808492 :9B1944A5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.016.0000ca49 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 13348, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de