Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250809140605\orcl_j000_20920_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250809140605\orcl_j000_20920_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:35612M/63366M, Ph+PgF:41075M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 20920, image: ORACLE.EXE (J000) *** 2025-08-09 14:06:05.131 *** SESSION ID:(193.45325) 2025-08-09 14:06:05.131 *** 2025-08-09 14:06:05.131 Process diagnostic dump for ORACLE.EXE (J000), OS id=20920, pid: 24, proc_ser: 194, sid: 193, sess_ser: 45325 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20920 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12888, hash value=1959786966 Current Wait Stack: Not in wait; last wait ended 1.040862 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.040899 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.000363 sec, exc=0.000363 sec, total=0.000363 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'log file sequential read' log#=0x29a8, block#=0x0, blocks=0x0 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.095833 sec of elapsed time 2: waited for 'log file sequential read' log#=0x29a8, block#=0x0, blocks=0x0 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000020 sec, exc=0.000020 sec, total=0.000020 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.467940 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0xd000, blocks=0x800 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000310 sec, exc=0.000310 sec, total=0.000310 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0xc800, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000322 sec, exc=0.000322 sec, total=0.000322 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0xc000, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000314 sec, exc=0.000314 sec, total=0.000314 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000115 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0xb800, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000354 sec, exc=0.000354 sec, total=0.000354 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.064300 sec of elapsed time 7: waited for 'log file sequential read' log#=0x29a8, block#=0x800, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000218 sec, exc=0.000218 sec, total=0.000218 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.003172 sec of elapsed time 8: waited for 'log file sequential read' log#=0x29a8, block#=0x0, blocks=0x0 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000146 sec of elapsed time 9: waited for 'log file sequential read' log#=0x29a8, block#=0x2, blocks=0x7fe wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000202 sec, exc=0.000202 sec, total=0.000202 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000191 sec of elapsed time Sampled Session History of session 193 serial 45325 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 14:05:58 - 14:06:04] not in wait at each sample [1 sample, 14:05:57] idle wait at each sample [session created at: 14:05:58] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-09 14:06:05.131 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 20920, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-09 14:04:07.690457 :BD7428CA: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-08-09 14:04:07.691293 :BD7428CF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:04:07.700113 :BD7428D2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:04:07.700 2025-08-09 14:04:07.700115 :BD7428D3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21432 pso_num=24 pso_serial#=182 2025-08-09 14:04:07.700254 :BD7428D4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21432 (legacy spawn) 2025-08-09 14:04:07.718408 :BD7428D5: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-08-09 14:04:07.718583 :BD7428DE: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-08-09 14:04:07.719414 :BD7428DF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:04:10.983686 :BD7429C5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:04:10.970 2025-08-09 14:04:10.983688 :BD7429C6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19784 pso_num=24 pso_serial#=183 2025-08-09 14:04:10.983828 :BD7429C7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19784 (legacy spawn) 2025-08-09 14:04:10.990418 :BD7429C8: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-08-09 14:04:10.990588 :BD7429CD: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-08-09 14:04:10.991401 :BD7429D2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:04:11.000170 :BD7429D5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:04:10.986 2025-08-09 14:04:11.000172 :BD7429D6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21384 pso_num=24 pso_serial#=184 2025-08-09 14:04:11.000303 :BD7429D7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21384 (legacy spawn) 2025-08-09 14:04:11.006836 :BD7429D8: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-08-09 14:04:11.007010 :BD7429E1: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-08-09 14:04:11.007853 :BD7429E2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:04:27.375732 :BD742EB5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:04:27.370 2025-08-09 14:04:27.375734 :BD742EB6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17492 pso_num=24 pso_serial#=185 2025-08-09 14:04:27.375874 :BD742EB7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17492 osp_idx=22 osp_ver=699915 osp_pg=0 (spawn #471389) 2025-08-09 14:04:27.375875 :BD742EB8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 14:04:27.375875 :BD742EB9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=144 time=3428936689 2025-08-09 14:04:27.375876 :BD742EBA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 14:04:27.375876 :BD742EBB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 14:04:27.375876 :BD742EBC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 14:04:27.375876 :BD742EBD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 14:04:27.375877 :BD742EBE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 14:04:27.376369 :BD742EBF: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-08-09 14:04:27.377101 :BD742ED4: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-08-09 14:04:27.388183 :BD742EF0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-09 14:04:27.388696 :BD742EF1:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-09 14:04:27.391010 :BD742EF2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-09 14:04:27.391351 :BD742EF3: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-08-09 14:04:27.391387 :BD742EF6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-09 14:04:27.392246 :BD742EFC: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-08-09 14:04:27.392367 :BD742F05: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-08-09 14:04:57.473014 :BD74386D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:05.205319 :BD743AD2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:05.196 2025-08-09 14:05:05.205321 :BD743AD3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20404 pso_num=24 pso_serial#=186 2025-08-09 14:05:05.205459 :BD743AD4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20404 (legacy spawn) 2025-08-09 14:05:05.212133 :BD743AD5: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-08-09 14:05:05.212341 :BD743ADD: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-08-09 14:05:05.213222 :BD743ADF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:14.424618 :BD743DA1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:14.414 2025-08-09 14:05:14.424620 :BD743DA2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17936 pso_num=24 pso_serial#=187 2025-08-09 14:05:14.424762 :BD743DA3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17936 (legacy spawn) 2025-08-09 14:05:14.431533 :BD743DA4: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-08-09 14:05:14.431733 :BD743DA9: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-08-09 14:05:14.432574 :BD743DAE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:20.686517 :BD743F74:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:20.671 2025-08-09 14:05:20.686519 :BD743F75:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19920 pso_num=24 pso_serial#=188 2025-08-09 14:05:20.686668 :BD743F76:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19920 (legacy spawn) 2025-08-09 14:05:20.693332 :BD743F77: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-08-09 14:05:20.693511 :BD743F7C: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-08-09 14:05:20.694343 :BD743F81:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:20.703129 :BD743F84:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:20.703 2025-08-09 14:05:20.703131 :BD743F85:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18072 pso_num=24 pso_serial#=189 2025-08-09 14:05:20.703265 :BD743F86:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18072 (legacy spawn) 2025-08-09 14:05:20.709796 :BD743F87: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-08-09 14:05:20.709960 :BD743F90: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-08-09 14:05:20.710794 :BD743F91:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:24.011161 :BD74408A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:24.000 2025-08-09 14:05:24.011163 :BD74408B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15328 pso_num=24 pso_serial#=190 2025-08-09 14:05:24.011309 :BD74408C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15328 (legacy spawn) 2025-08-09 14:05:24.017887 :BD74408D: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-08-09 14:05:24.018079 :BD744092: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-08-09 14:05:24.018903 :BD744097:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:24.027737 :BD74409A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:24.015 2025-08-09 14:05:24.027739 :BD74409B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21500 pso_num=24 pso_serial#=191 2025-08-09 14:05:24.027876 :BD74409C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21500 (legacy spawn) 2025-08-09 14:05:24.034431 :BD74409D: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-08-09 14:05:24.034597 :BD7440A6: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-08-09 14:05:24.035444 :BD7440A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:27.446839 :BD7441CE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:27.440 2025-08-09 14:05:27.446841 :BD7441CF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19504 pso_num=24 pso_serial#=192 2025-08-09 14:05:27.446979 :BD7441D0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19504 osp_idx=22 osp_ver=699922 osp_pg=0 (spawn #471391) 2025-08-09 14:05:27.446980 :BD7441D1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 14:05:27.446980 :BD7441D2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=145 time=3428996751 2025-08-09 14:05:27.446980 :BD7441D3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 14:05:27.446981 :BD7441D4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 14:05:27.446981 :BD7441D5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 14:05:27.446981 :BD7441D6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 14:05:27.446982 :BD7441D7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 14:05:27.447472 :BD7441D8: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-08-09 14:05:27.448111 :BD7441ED: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-08-09 14:05:27.455733 :BD744209:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-09 14:05:27.456093 :BD74420A: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-08-09 14:05:27.456127 :BD74420D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-09 14:05:27.457096 :BD744226: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-08-09 14:05:27.457221 :BD74422F: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-08-09 14:05:57.573225 :BD7455E0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:58.064320 :BD745665:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:58.059 2025-08-09 14:05:58.064322 :BD745666:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21284 pso_num=24 pso_serial#=193 2025-08-09 14:05:58.064462 :BD745667:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21284 osp_idx=22 osp_ver=699923 osp_pg=0 (spawn #471394) 2025-08-09 14:05:58.064463 :BD745668:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 14:05:58.064464 :BD745669:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=146 time=3429027376 2025-08-09 14:05:58.064464 :BD74566A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 14:05:58.064464 :BD74566B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 14:05:58.064465 :BD74566C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 14:05:58.064465 :BD74566D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 14:05:58.064465 :BD74566E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 14:05:58.064969 :BD74566F: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-08-09 14:05:58.065013 :BD745675: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-08-09 14:05:58.069274 :BD745687:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-09 14:05:58.069320 :BD745688:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-09 14:05:58.069579 :BD745689:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-09 14:05:58.069595 :BD74568A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-09 14:05:58.069806 :BD74568B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-09 14:05:58.069819 :BD74568C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-09 14:05:58.070055 :BD74568D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-09 14:05:58.070076 :BD74568E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-09 14:05:58.070342 :BD74568F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-09 14:05:58.070356 :BD745690:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-09 14:05:58.070582 :BD745691:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-08-09 14:05:58.070595 :BD745692:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-08-09 14:05:58.072944 :BD7456C0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 14:05:58.161181 :BD7457A2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 14:05:58.153 2025-08-09 14:05:58.161184 :BD7457A3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20920 pso_num=24 pso_serial#=194 2025-08-09 14:05:58.161935 :BD7457A4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20920 osp_idx=22 osp_ver=699924 osp_pg=0 (spawn #471396) 2025-08-09 14:05:58.161936 :BD7457A5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 14:05:58.161937 :BD7457A6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=147 time=3429027470 2025-08-09 14:05:58.161937 :BD7457A7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 14:05:58.161938 :BD7457A8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 14:05:58.161938 :BD7457A9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 14:05:58.161939 :BD7457AA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 14:05:58.161939 :BD7457AB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 14:05:58.162670 :BD7457AC: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-08-09 14:05:58.167705 :BD7457CB: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-08-09 14:05:58.167750 :BD7457CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-09 14:05:58.184741 :BD7457E4: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-08-09 14:05:58.184946 :BD7457EC: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-08-09 14:05:58.185279 :BD7457F4: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-08-09 14:05:58.185530 :BD7457FC: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-08-09 14:05:58.185821 :BD745804: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-08-09 14:05:58.185982 :BD74580C: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-08-09 14:05:58.186141 :BD745814: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-08-09 14:05:58.186287 :BD74581C: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-08-09 14:05:58.203768 :BD745824: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-08-09 14:05:59.449548*:BD74589A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026E73AD0 estart=1754719559 eid=21198405 ctx=0x0000000777968158 cwh=0x00000000270A0068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-09 14:05:59.449548*:BD74589B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026E73AD0 curCtx=0x00000000271619A8 pubTabIdxCnt=0 2025-08-09 14:05:59.449548*:BD74589C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026E73AD0 2025-08-09 14:05:59.449548*:BD74589D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026E73AD0 2025-08-09 14:05:59.449548*:BD74589E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026EEB820 estart=1754719557 eid=16777508 ctx=0x000000077B54EAC8 cwh=0x000000000FD80608 cpu=1093750 buffg=59561 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-09 14:05:59.449548*:BD74589F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026EEB820 curCtx=0x0000000027169FF0 pubTabIdxCnt=0 2025-08-09 14:05:59.449548*:BD7458A0:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026EEB820 2025-08-09 14:05:59.449548*:BD7458A1:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026EEB820 2025-08-09 14:05:59.449548*:BD7458A2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026E73AD0 curCtx=0x00000000271619A8 xsc->flg4=65792 flags=1 2025-08-09 14:05:59.449548*:BD7458A3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026E73AD0 2025-08-09 14:05:59.863306 :BD745933: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-08-09 14:05:59.866379 :BD745943:db_trace:ktu.c@4871:ktuaex1r(): [10442:24:193] Extension usn 7, ext 0, nex 3 2025-08-09 14:05:59.866387 :BD745944:db_trace:ktusm.c@2117:ktusmasp(): [10445:24:193] des1:tsn:1 rdba:0x00815e00 where: kdiwh22: kdifind 2025-08-09 14:05:59.866387*:BD745945:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026EEB820 curCtx=0x0000000027169FF0 xsc->flg4=65792 flags=2 2025-08-09 14:05:59.866387*:BD745946:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026EEB820 2025-08-09 14:05:59.867868 :BD745947: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-08-09 14:05:59.888656 :BD745975: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-08-09 14:05:59.890666 :BD74597D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-09 14:05:59.890892 :BD74597E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-09 14:05:59.891819 :BD74597F: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-08-09 14:05:59.891910 :BD745982: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-08-09 14:05:59.892761 :BD745994: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-08-09 14:05:59.894389 :BD74599C: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-08-09 14:05:59.894680 :BD7459A4: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-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-09 14:05:59.939082*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-09 14:06:00.011912 :BD745A25: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-08-09 14:06:01.864303 :BD745B1D: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-08-09 14:06:01.864315 :BD745B1E:db_trace:ktur.c@3079:ktuabt(): [10444:24:193] ABORT TRANSACTION - xid: 0x000a.004.00177065 2025-08-09 14:06:04.540245 :BD745CE3: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-08-09 14:06:04.541650 :BD745CEB: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-08-09 14:06:04.552817 :BD745CF3: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-08-09 14:06:04.580976 :BD745CFB: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 20920, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de