Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705100737\orcl_j000_18248_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705100737\orcl_j000_18248_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:37544M/63366M, Ph+PgF:43472M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 18248, image: ORACLE.EXE (J000) *** 2025-07-05 10:07:37.610 *** SESSION ID:(193.18035) 2025-07-05 10:07:37.610 *** 2025-07-05 10:07:37.610 Process diagnostic dump for ORACLE.EXE (J000), OS id=18248, pid: 24, proc_ser: 188, sid: 193, sess_ser: 18035 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18248 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12701, hash value=2331661668 Current Wait Stack: Not in wait; last wait ended 3.255609 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 3.255635 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000149 sec, exc=0.000149 sec, total=0.000149 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.050003 sec of elapsed time 1: 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.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.754723 sec of elapsed time 2: 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.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025124 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012751 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.002651 sec, exc=0.002651 sec, total=0.002651 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049069 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.004866 sec, exc=0.004866 sec, total=0.004866 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007894 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.005723 sec, exc=0.005723 sec, total=0.005723 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262103 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000137 sec, exc=0.000137 sec, total=0.000137 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000284 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.006469 sec, exc=0.006469 sec, total=0.006469 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007885 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=30 seq_num=31 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.000054 sec of elapsed time Sampled Session History of session 193 serial 18035 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 10:07:32 - 10:07:36] not in wait at each sample [1 sample, 10:07:31] idle wait at each sample [session created at: 10:07:32] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-05 10:07:37.610 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 18248, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-05 10:05:54.404592 :ADCD7006:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 10:05:54.404592 :ADCD7007:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=98 time=390730298 2025-07-05 10:05:54.404593 :ADCD7008:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 10:05:54.404593 :ADCD7009:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 10:05:54.404593 :ADCD700A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 10:05:54.404593 :ADCD700B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 10:05:54.404594 :ADCD700C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 10:05:54.405246 :ADCD700D: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-07-05 10:05:54.405980 :ADCD7022: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-07-05 10:05:54.414916 :ADCD703E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-05 10:05:54.415398 :ADCD703F: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-07-05 10:05:54.415464 :ADCD7042:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-05 10:05:54.416543 :ADCD7048: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-07-05 10:05:54.419045 :ADCD7051: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-07-05 10:06:24.510769 :ADCD7BC7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:28.378632 :ADCD7D06:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:28.370 2025-07-05 10:06:28.378634 :ADCD7D07:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10368 pso_num=24 pso_serial#=175 2025-07-05 10:06:28.378794 :ADCD7D08:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10368 (legacy spawn) 2025-07-05 10:06:28.385894 :ADCD7D09: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-07-05 10:06:28.386064 :ADCD7D12: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-07-05 10:06:28.386931 :ADCD7D13:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:48.340895 :ADCD8377:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:48.330 2025-07-05 10:06:48.340897 :ADCD8378:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16084 pso_num=24 pso_serial#=176 2025-07-05 10:06:48.341043 :ADCD8379:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16084 (legacy spawn) 2025-07-05 10:06:48.348192 :ADCD837A: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-07-05 10:06:48.348360 :ADCD837F: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-07-05 10:06:48.349183 :ADCD8384:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:49.912169 :ADCD8413:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:49.909 2025-07-05 10:06:49.912171 :ADCD8414:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15628 pso_num=24 pso_serial#=177 2025-07-05 10:06:49.912308 :ADCD8415:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15628 (legacy spawn) 2025-07-05 10:06:49.918873 :ADCD8416: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-07-05 10:06:49.919046 :ADCD841F: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-07-05 10:06:49.919939 :ADCD8420:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:49.928809 :ADCD8423:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:49.924 2025-07-05 10:06:49.928812 :ADCD8424:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16328 pso_num=24 pso_serial#=178 2025-07-05 10:06:49.928947 :ADCD8425:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16328 (legacy spawn) 2025-07-05 10:06:49.935535 :ADCD8426: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-07-05 10:06:49.935706 :ADCD842C: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-07-05 10:06:49.936501 :ADCD842F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:53.242730 :ADCD8520:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:53.228 2025-07-05 10:06:53.242732 :ADCD8521:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18420 pso_num=24 pso_serial#=179 2025-07-05 10:06:53.242879 :ADCD8522:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18420 (legacy spawn) 2025-07-05 10:06:53.249894 :ADCD8523: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-07-05 10:06:53.249926 :ADCD8526: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-07-05 10:06:53.250090 :ADCD8529: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-07-05 10:06:53.250981 :ADCD853A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:53.260529 :ADCD853D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:53.260 2025-07-05 10:06:53.260531 :ADCD853E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8984 pso_num=24 pso_serial#=180 2025-07-05 10:06:53.260675 :ADCD853F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8984 (legacy spawn) 2025-07-05 10:06:53.267950 :ADCD8540: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-07-05 10:06:53.268159 :ADCD8548: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-07-05 10:06:53.269041 :ADCD854A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:06:54.485030 :ADCD85E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:06:54.479 2025-07-05 10:06:54.485032 :ADCD85E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8784 pso_num=24 pso_serial#=181 2025-07-05 10:06:54.485173 :ADCD85E3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8784 osp_idx=22 osp_ver=520136 osp_pg=0 (spawn #354178) 2025-07-05 10:06:54.485174 :ADCD85E4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 10:06:54.485174 :ADCD85E5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=99 time=390790376 2025-07-05 10:06:54.485174 :ADCD85E6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 10:06:54.485175 :ADCD85E7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 10:06:54.485175 :ADCD85E8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 10:06:54.485175 :ADCD85E9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 10:06:54.485176 :ADCD85EA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 10:06:54.485854 :ADCD85EB: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-07-05 10:06:54.486634 :ADCD8600: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-07-05 10:06:54.498971 :ADCD861C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 10:06:54.499450 :ADCD861D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 10:06:54.502538 :ADCD861E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-05 10:06:54.502945 :ADCD861F: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-07-05 10:06:54.502999 :ADCD8622:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-05 10:06:54.504004 :ADCD8628: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-07-05 10:06:54.504129 :ADCD8631: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-07-05 10:07:24.592499 :ADCD9005:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:26.325953 :ADCD907D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:26.311 2025-07-05 10:07:26.325955 :ADCD907E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10808 pso_num=24 pso_serial#=182 2025-07-05 10:07:26.326096 :ADCD907F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10808 (legacy spawn) 2025-07-05 10:07:26.332765 :ADCD9080: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-07-05 10:07:26.332948 :ADCD9085: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-07-05 10:07:26.333763 :ADCD908A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:26.342897 :ADCD908D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:26.343 2025-07-05 10:07:26.342900 :ADCD908E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16256 pso_num=24 pso_serial#=183 2025-07-05 10:07:26.343038 :ADCD908F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16256 (legacy spawn) 2025-07-05 10:07:26.349707 :ADCD9090: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-07-05 10:07:26.349874 :ADCD9099: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-07-05 10:07:26.350684 :ADCD909A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:29.497884 :ADCD918C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:29.484 2025-07-05 10:07:29.497887 :ADCD918D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18160 pso_num=24 pso_serial#=184 2025-07-05 10:07:29.498031 :ADCD918E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18160 (legacy spawn) 2025-07-05 10:07:29.505068 :ADCD919C: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-07-05 10:07:29.505239 :ADCD91A4: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-07-05 10:07:29.506048 :ADCD91A6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:29.523980 :ADCD91A9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:29.515 2025-07-05 10:07:29.523982 :ADCD91AA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18276 pso_num=24 pso_serial#=185 2025-07-05 10:07:29.524118 :ADCD91AB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18276 (legacy spawn) 2025-07-05 10:07:29.531012 :ADCD91AC: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-07-05 10:07:29.531176 :ADCD91B5: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-07-05 10:07:29.531976 :ADCD91B6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:30.524069 :ADCD9236:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:30.515 2025-07-05 10:07:30.524071 :ADCD9237:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6512 pso_num=24 pso_serial#=186 2025-07-05 10:07:30.524209 :ADCD9238:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6512 (legacy spawn) 2025-07-05 10:07:30.531192 :ADCD9239: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-07-05 10:07:30.531367 :ADCD923E: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-07-05 10:07:30.532234 :ADCD9243:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:32.551690 :ADCD92E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:32.546 2025-07-05 10:07:32.551692 :ADCD92E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15676 pso_num=24 pso_serial#=187 2025-07-05 10:07:32.551836 :ADCD92E3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15676 osp_idx=22 osp_ver=520142 osp_pg=0 (spawn #354180) 2025-07-05 10:07:32.551836 :ADCD92E4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 10:07:32.551837 :ADCD92E5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=100 time=390828439 2025-07-05 10:07:32.551837 :ADCD92E6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 10:07:32.551838 :ADCD92E7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 10:07:32.551838 :ADCD92E8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 10:07:32.551838 :ADCD92E9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 10:07:32.551839 :ADCD92EA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 10:07:32.552406 :ADCD92EB: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-07-05 10:07:32.552451 :ADCD92F1: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-07-05 10:07:32.556654 :ADCD9303:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-07-05 10:07:32.556699 :ADCD9304:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-07-05 10:07:32.556971 :ADCD9305:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-07-05 10:07:32.556994 :ADCD9306:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-07-05 10:07:32.557220 :ADCD9307:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-07-05 10:07:32.557233 :ADCD9308:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-07-05 10:07:32.557439 :ADCD9309:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-07-05 10:07:32.557452 :ADCD930A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-07-05 10:07:32.557667 :ADCD930B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-07-05 10:07:32.557680 :ADCD930C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-07-05 10:07:32.557881 :ADCD930D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-07-05 10:07:32.557894 :ADCD930E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-07-05 10:07:32.560991 :ADCD933C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-05 10:07:32.658608 :ADCD9426:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-05 10:07:32.656 2025-07-05 10:07:32.658610 :ADCD9427:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18248 pso_num=24 pso_serial#=188 2025-07-05 10:07:32.658807 :ADCD9428:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18248 osp_idx=22 osp_ver=520143 osp_pg=0 (spawn #354182) 2025-07-05 10:07:32.658809 :ADCD9429:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 10:07:32.658809 :ADCD942A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=101 time=390828533 2025-07-05 10:07:32.658809 :ADCD942B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 10:07:32.658809 :ADCD942C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 10:07:32.658809 :ADCD942D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 10:07:32.658811 :ADCD942E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 10:07:32.658811 :ADCD942F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 10:07:32.660226 :ADCD9430: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-07-05 10:07:32.664704 :ADCD9445: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-07-05 10:07:32.664736 :ADCD9447:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-05 10:07:32.675900 :ADCD9468: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-07-05 10:07:32.676036 :ADCD946B: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-07-05 10:07:32.676143 :ADCD9472: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-07-05 10:07:32.676268 :ADCD9484: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-07-05 10:07:32.676378 :ADCD948C: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-07-05 10:07:32.676502 :ADCD9494: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-07-05 10:07:32.676629 :ADCD949C: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-07-05 10:07:32.676731 :ADCD94A4: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-07-05 10:07:32.676982 :ADCD94AC: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-07-05 10:07:32.677152 :ADCD94B4: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-07-05 10:07:32.677268 :ADCD94BC: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-07-05 10:07:32.677372 :ADCD94C4: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-07-05 10:07:32.689085 :ADCD94CC: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-07-05 10:07:34.264867*:ADCD95D0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D9B820 estart=1751681252 eid=16777432 ctx=0x000000077B54EAC8 cwh=0x000000000FD80608 cpu=1453125 buffg=78611 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-05 10:07:34.264867*:ADCD95D1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D9B820 curCtx=0x0000000029A69FF0 pubTabIdxCnt=0 2025-07-05 10:07:34.264867*:ADCD95D2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D9B820 2025-07-05 10:07:34.264867*:ADCD95D3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D9B820 2025-07-05 10:07:34.271132 :ADCD95DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] 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-07-05 10:07:34.280502*:ADCD960E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D9B820 curCtx=0x0000000029A69FF0 xsc->flg4=65792 flags=2 2025-07-05 10:07:34.280502*:ADCD960F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D9B820 2025-07-05 10:07:34.285992 :ADCD9610: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-07-05 10:07:34.306095 :ADCD9648: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-07-05 10:07:34.307983 :ADCD9651:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-05 10:07:34.308171 :ADCD9652:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-05 10:07:34.309137 :ADCD9653: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-07-05 10:07:34.309270 :ADCD965B: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-07-05 10:07:34.310121 :ADCD9663: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-07-05 10:07:34.311660 :ADCD966B: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-07-05 10:07:34.311958 :ADCD9673: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-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-05 10:07:34.405499*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-05 10:07:34.479592 :ADCD96E3: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-07-05 10:07:36.886176 :ADCD9818: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-07-05 10:07:36.886191 :ADCD9819:db_trace:ktur.c@3079:ktuabt(): [10444:24:193] ABORT TRANSACTION - xid: 0x0008.019.0000ce15 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 18248, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de