Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629181040\orcl_j000_15516_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629181040\orcl_j000_15516_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:36547M/63366M, Ph+PgF:42656M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15516, image: ORACLE.EXE (J000) *** 2025-06-29 18:10:41.064 *** SESSION ID:(177.3523) 2025-06-29 18:10:41.064 *** 2025-06-29 18:10:41.064 Process diagnostic dump for ORACLE.EXE (J000), OS id=15516, pid: 22, proc_ser: 224, sid: 177, sess_ser: 3523 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15516 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12679, hash value=452258386 Current Wait Stack: Not in wait; last wait ended 2.446997 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.447022 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.000143 sec, exc=0.000143 sec, total=0.000143 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.044946 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.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.757904 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.026432 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.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.012890 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.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049613 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.006140 sec, exc=0.006140 sec, total=0.006140 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=34 seq_num=35 snap_id=1 wait times: snap=0.005999 sec, exc=0.005999 sec, total=0.005999 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.260631 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.000133 sec, exc=0.000133 sec, total=0.000133 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000287 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.002583 sec, exc=0.002583 sec, total=0.002583 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007933 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 177 serial 3523 --------------------------------------------------- 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, 18:10:37 - 18:10:40] not in wait at each sample [1 sample, 18:10:36] idle wait at each sample [session created at: 18:10:36] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 18:10:41.064 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15516, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 18:08:20.750985 :AB506D78:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:08:20.751811 :AB506D7D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:08:20.930716 :AB506D80:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:08:20.923 2025-06-29 18:08:20.930718 :AB506D81:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14056 pso_num=22 pso_serial#=215 2025-06-29 18:08:20.930879 :AB506D82:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14056 (legacy spawn) 2025-06-29 18:08:20.937972 :AB506D83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:20.938169 :AB506D8A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:08:20.939164 :AB506D8D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:08:27.628532 :AB506FA0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:08:27.614 2025-06-29 18:08:27.628534 :AB506FA1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12756 pso_num=22 pso_serial#=216 2025-06-29 18:08:27.628676 :AB506FA2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12756 (legacy spawn) 2025-06-29 18:08:27.635365 :AB506FA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:27.635549 :AB506FA8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:08:27.722262 :AB506FC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:08:30.925180 :AB5070D9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:08:30.912 2025-06-29 18:08:30.925182 :AB5070DA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17960 pso_num=22 pso_serial#=217 2025-06-29 18:08:30.925320 :AB5070DB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17960 (legacy spawn) 2025-06-29 18:08:30.931742 :AB5070EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:30.931914 :AB5070F8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:08:30.932694 :AB5070F9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:08:30.941279 :AB5070FC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:08:30.928 2025-06-29 18:08:30.941280 :AB5070FD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17544 pso_num=22 pso_serial#=218 2025-06-29 18:08:30.941414 :AB5070FE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17544 (legacy spawn) 2025-06-29 18:08:30.963221 :AB5070FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:30.963386 :AB507107:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:08:30.964207 :AB507108:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:08:38.170239 :AB507347:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:08:38.164 2025-06-29 18:08:38.170241 :AB507348:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16440 pso_num=22 pso_serial#=219 2025-06-29 18:08:38.170379 :AB507349:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16440 osp_idx=0 osp_ver=359331 osp_pg=0 (spawn #335302) 2025-06-29 18:08:38.170379 :AB50734A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 18:08:38.170380 :AB50734B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=154 time=4196261360 2025-06-29 18:08:38.170380 :AB50734C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 18:08:38.170380 :AB50734D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 18:08:38.170381 :AB50734E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 18:08:38.170381 :AB50734F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 18:08:38.170381 :AB507350:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 18:08:38.170911 :AB507351:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 18:08:38.171621 :AB507366:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:38.182784 :AB507382:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 18:08:38.183318 :AB507383:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 18:08:38.185740 :AB507384:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 18:08:38.186072 :AB507385:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:38.186106 :AB507388:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 18:08:38.186998 :AB50738E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:08:38.187137 :AB507397:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:09:08.269389 :AB507D2A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:09:23.059095 :AB5081A4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:09:23.051 2025-06-29 18:09:23.059096 :AB5081A5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17984 pso_num=22 pso_serial#=220 2025-06-29 18:09:23.059237 :AB5081A6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17984 (legacy spawn) 2025-06-29 18:09:23.065688 :AB5081A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:09:23.065864 :AB5081AF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:09:23.066749 :AB5081B1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:09:28.925885 :AB50838E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:09:28.917 2025-06-29 18:09:28.925887 :AB50838F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14672 pso_num=22 pso_serial#=221 2025-06-29 18:09:28.926031 :AB508390:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14672 (legacy spawn) 2025-06-29 18:09:28.932474 :AB508391:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:09:28.932658 :AB50839A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:09:28.933445 :AB50839B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:09:38.242090 :AB508671:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:09:38.236 2025-06-29 18:09:38.242091 :AB508672:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13176 pso_num=22 pso_serial#=222 2025-06-29 18:09:38.242280 :AB508673:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13176 osp_idx=0 osp_ver=359334 osp_pg=0 (spawn #335304) 2025-06-29 18:09:38.242281 :AB508674:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 18:09:38.242281 :AB508675:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=155 time=4196321438 2025-06-29 18:09:38.242282 :AB508676:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 18:09:38.242282 :AB508677:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 18:09:38.242283 :AB508678:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 18:09:38.242283 :AB508679:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 18:09:38.242284 :AB50867A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 18:09:38.242798 :AB50867B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 18:09:38.243443 :AB508690:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:09:38.250985 :AB5086AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 18:09:38.251366 :AB5086AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:09:38.251405 :AB5086B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 18:09:38.252284 :AB5086B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:09:38.252407 :AB5086BE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:09:40.019912 :AB50871D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-29 18:10:03.273936 :AB508F10:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-29 18:10:03.273961*:AB508F14:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCD57B8 estart=1751191802 eid=17079028 ctx=0x0000000777E33918 cwh=0x000000000FCC04C0 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 18:10:03.273961*:AB508F15:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCD57B8 curCtx=0x000000000FCD4718 pubTabIdxCnt=0 2025-06-29 18:10:03.273961*:AB508F16:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCD57B8 2025-06-29 18:10:03.273961*:AB508F17:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCD57B8 2025-06-29 18:10:03.273961*:AB508F18:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCD57B8 curCtx=0x000000000FCD4718 xsc->flg4=65792 flags=1 2025-06-29 18:10:03.273961*:AB508F19:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCD57B8 2025-06-29 18:10:03.278950 :AB508F1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:03.284324 :AB508F22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:03.285114 :AB508F2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:03.285237 :AB508F33:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-29 18:10:33.359581 :AB50989D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:10:36.750602 :AB5099C7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:10:36.745 2025-06-29 18:10:36.750604 :AB5099C8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2796 pso_num=22 pso_serial#=223 2025-06-29 18:10:36.750737 :AB5099C9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=2796 osp_idx=0 osp_ver=359335 osp_pg=0 (spawn #335306) 2025-06-29 18:10:36.750738 :AB5099CA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 18:10:36.750738 :AB5099CB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=156 time=4196379938 2025-06-29 18:10:36.750739 :AB5099CC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 18:10:36.750739 :AB5099CD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 18:10:36.750740 :AB5099CE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 18:10:36.750740 :AB5099CF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 18:10:36.750740 :AB5099D0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 18:10:36.751274 :AB5099D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.751327 :AB5099D7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-29 18:10:36.755469 :AB5099EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-29 18:10:36.755513 :AB5099EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-29 18:10:36.755786 :AB5099F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-29 18:10:36.755803 :AB5099F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-29 18:10:36.756034 :AB5099F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-29 18:10:36.756047 :AB5099F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-29 18:10:36.756262 :AB5099F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-29 18:10:36.756277 :AB5099F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-29 18:10:36.756492 :AB5099F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-29 18:10:36.756505 :AB5099F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-29 18:10:36.756716 :AB5099F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-29 18:10:36.756730 :AB5099F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-29 18:10:36.759198 :AB509A2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 18:10:36.849233 :AB509B2D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 18:10:36.838 2025-06-29 18:10:36.849235 :AB509B2E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15516 pso_num=22 pso_serial#=224 2025-06-29 18:10:36.849382 :AB509B2F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15516 osp_idx=0 osp_ver=359336 osp_pg=0 (spawn #335308) 2025-06-29 18:10:36.849382 :AB509B30:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 18:10:36.849383 :AB509B31:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=157 time=4196380032 2025-06-29 18:10:36.849383 :AB509B32:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 18:10:36.849383 :AB509B33:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 18:10:36.849384 :AB509B34:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 18:10:36.849384 :AB509B35:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 18:10:36.849384 :AB509B36:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 18:10:36.850481 :AB509B37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.856003 :AB509B61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.856045 :AB509B64:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-06-29 18:10:36.872973 :AB509B7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.873348 :AB509B82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.873512 :AB509B8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.873678 :AB509B92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.873836 :AB509B9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.873989 :AB509BA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.874135 :AB509BAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.874291 :AB509BB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.874457 :AB509BBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.874601 :AB509BC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.874745 :AB509BCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.874889 :AB509BD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.875039 :AB509BDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.875179 :AB509BE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.875315 :AB509BEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.875483 :AB509BF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.875624 :AB509BFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.875771 :AB509C02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.876092 :AB509C0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.876337 :AB509C12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:36.895340 :AB509C1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.088882*:AB509D2C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000023863AD0 estart=1751191837 eid=19928441 ctx=0x0000000777968158 cwh=0x0000000024A90068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 18:10:38.088882*:AB509D2D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000023863AD0 curCtx=0x0000000024B519A8 pubTabIdxCnt=0 2025-06-29 18:10:38.088882*:AB509D2E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000023863AD0 2025-06-29 18:10:38.088882*:AB509D2F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000023863AD0 2025-06-29 18:10:38.088882*:AB509D30:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000238DB820 estart=1751191836 eid=16777424 ctx=0x000000077B54EAC8 cwh=0x0000000023800608 cpu=968750 buffg=56825 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-29 18:10:38.088882*:AB509D31:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000238DB820 curCtx=0x0000000024B59FF0 pubTabIdxCnt=0 2025-06-29 18:10:38.088882*:AB509D32:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000238DB820 2025-06-29 18:10:38.088882*:AB509D33:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000238DB820 2025-06-29 18:10:38.088882*:AB509D34:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000023863AD0 curCtx=0x0000000024B519A8 xsc->flg4=65792 flags=1 2025-06-29 18:10:38.088882*:AB509D35:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000023863AD0 2025-06-29 18:10:38.559073 :AB509E35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] 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-06-29 18:10:38.559073*:AB509E3C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000238DB820 curCtx=0x0000000024B59FF0 xsc->flg4=65792 flags=2 2025-06-29 18:10:38.559073*:AB509E3D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000238DB820 2025-06-29 18:10:38.559985 :AB509E3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.580176 :AB509E4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.582138 :AB509E5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 18:10:38.582329 :AB509E61:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 18:10:38.583167 :AB509E6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.583259 :AB509E6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.584091 :AB509E7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.585513 :AB509E87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.585865 :AB509E8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-29 18:10:38.620135*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-29 18:10:38.692833 :AB509EE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:40.573728 :AB50A014:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 18:10:40.573743 :AB50A015:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x000a.00d.001706e5 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15516, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de