Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831220734\orcl_j000_21696_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831220734\orcl_j000_21696_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:35074M/63366M, Ph+PgF:40047M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 21696, image: ORACLE.EXE (J000) *** 2025-08-31 22:07:34.647 *** SESSION ID:(201.21691) 2025-08-31 22:07:34.647 *** 2025-08-31 22:07:34.647 Process diagnostic dump for ORACLE.EXE (J000), OS id=21696, pid: 25, proc_ser: 110, sid: 201, sess_ser: 21691 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21696 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13014, hash value=2273482926 Current Wait Stack: Not in wait; last wait ended 2.824438 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.824464 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.000147 sec, exc=0.000147 sec, total=0.000147 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.053592 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.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.760592 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.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.026776 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.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012799 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.000121 sec, exc=0.000121 sec, total=0.000121 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049709 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.008111 sec, exc=0.008111 sec, total=0.008111 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007752 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.000116 sec, exc=0.000116 sec, total=0.000116 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.260638 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.006921 sec, exc=0.006921 sec, total=0.006921 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000177 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000126 sec, exc=0.000126 sec, total=0.000126 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007973 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.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000070 sec of elapsed time Sampled Session History of session 201 serial 21691 --------------------------------------------------- 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, 22:07:30 - 22:07:34] not in wait at each sample [1 sample, 22:07:29] idle wait at each sample [session created at: 22:07:30] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 22:07:34.647 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 21696, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 22:06:07.904692 :C7443325:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22332 pso_num=25 pso_serial#=97 2025-08-31 22:06:07.904824 :C7443326:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22332 (legacy spawn) 2025-08-31 22:06:07.911237 :C7443327:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:07.911399 :C7443330:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:06:07.912200 :C7443331:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:06:08.053438 :C7443334:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:06:08.045 2025-08-31 22:06:08.053440 :C7443335:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22460 pso_num=25 pso_serial#=98 2025-08-31 22:06:08.053587 :C7443336:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22460 (legacy spawn) 2025-08-31 22:06:08.060162 :C7443337:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:08.060334 :C7443340:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:06:08.061155 :C7443341:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:06:11.137254 :C7443429:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:06:11.124 2025-08-31 22:06:11.137256 :C744342A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20692 pso_num=25 pso_serial#=99 2025-08-31 22:06:11.137395 :C744342B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20692 (legacy spawn) 2025-08-31 22:06:11.143841 :C744342C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:11.144024 :C7443431:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:06:11.144810 :C7443436:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:06:11.153442 :C7443439:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:06:11.140 2025-08-31 22:06:11.153443 :C744343A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17404 pso_num=25 pso_serial#=100 2025-08-31 22:06:11.153584 :C744343B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17404 (legacy spawn) 2025-08-31 22:06:11.160100 :C744343C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:11.160262 :C7443445:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:06:11.161041 :C7443446:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:06:13.413751 :C7443504:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:06:13.405 2025-08-31 22:06:13.413753 :C7443505:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21748 pso_num=25 pso_serial#=101 2025-08-31 22:06:13.413890 :C7443506:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21748 (legacy spawn) 2025-08-31 22:06:13.420435 :C7443507:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:13.420609 :C744350C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:06:13.421433 :C7443511:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:06:16.677909 :C7443615:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:06:16.672 2025-08-31 22:06:16.677911 :C7443616:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21992 pso_num=25 pso_serial#=102 2025-08-31 22:06:16.678055 :C7443617:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21992 osp_idx=23 osp_ver=276889 osp_pg=0 (spawn #545849) 2025-08-31 22:06:16.678055 :C7443618:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 22:06:16.678056 :C7443619:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=1063627987 2025-08-31 22:06:16.678056 :C744361A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 22:06:16.678056 :C744361B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 22:06:16.678057 :C744361C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 22:06:16.678057 :C744361D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 22:06:16.678058 :C744361E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 22:06:16.678549 :C744361F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 22:06:16.679195 :C7443634:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:16.684662 :C7443650:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:16.685751 :C7443658:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:16.685897 :C7443661:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:06:31.694554 :C7443B11:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-31 22:06:31.694576*:C7443B14:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1756649191 eid=17033365 ctx=0x000000077BD98A08 cwh=0x0000000026B902A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-31 22:06:31.694576*:C7443B15:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F300 pubTabIdxCnt=0 2025-08-31 22:06:31.694576*:C7443B16:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-08-31 22:06:31.694576*:C7443B17:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-08-31 22:06:31.694576*:C7443B18:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F300 xsc->flg4=65792 flags=2 2025-08-31 22:06:31.694576*:C7443B19:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-08-31 22:06:31.695107 :C7443B1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:31.705921 :C7443B22:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-31 22:06:31.706386 :C7443B23:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-31 22:06:31.708773 :C7443B24:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 22:06:31.709121 :C7443B25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:31.709157 :C7443B28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 22:06:31.710020 :C7443B2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:06:31.710158 :C7443B36:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:01.814840 :C74444EB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:14.029547 :C7444894:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:14.015 2025-08-31 22:07:14.029549 :C7444895:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23040 pso_num=25 pso_serial#=103 2025-08-31 22:07:14.029692 :C7444896:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23040 (legacy spawn) 2025-08-31 22:07:14.036489 :C74448A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:14.036805 :C74448A8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:14.037757 :C74448AD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:15.619035 :C7444923:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:15.610 2025-08-31 22:07:15.619038 :C7444924:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21428 pso_num=25 pso_serial#=104 2025-08-31 22:07:15.619179 :C7444925:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21428 (legacy spawn) 2025-08-31 22:07:15.625977 :C7444926:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:15.626151 :C744492B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:15.627043 :C7444930:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:20.503988 :C7444AA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:20.504 2025-08-31 22:07:20.503990 :C7444AAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22252 pso_num=25 pso_serial#=105 2025-08-31 22:07:20.504135 :C7444AAB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22252 (legacy spawn) 2025-08-31 22:07:20.511095 :C7444AAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:20.511300 :C7444AB1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:20.512251 :C7444AB6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:20.521693 :C7444AB9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:20.520 2025-08-31 22:07:20.521696 :C7444ABA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22964 pso_num=25 pso_serial#=106 2025-08-31 22:07:20.521846 :C7444ABB:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22964 (legacy spawn) 2025-08-31 22:07:20.528779 :C7444ABC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:20.528958 :C7444AC5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:20.529863 :C7444AC6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:23.627532 :C7444BD1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:23.615 2025-08-31 22:07:23.627534 :C7444BD2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22844 pso_num=25 pso_serial#=107 2025-08-31 22:07:23.627671 :C7444BD3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22844 (legacy spawn) 2025-08-31 22:07:23.634200 :C7444BD4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:23.634370 :C7444BD9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:23.635173 :C7444BDE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:23.643990 :C7444BE1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:23.630 2025-08-31 22:07:23.643992 :C7444BE2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12980 pso_num=25 pso_serial#=108 2025-08-31 22:07:23.644128 :C7444BE3:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12980 (legacy spawn) 2025-08-31 22:07:23.650756 :C7444BF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:23.650934 :C7444C00:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 22:07:23.651743 :C7444C01:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:29.939148 :C7444E03:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:29.933 2025-08-31 22:07:29.939150 :C7444E04:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22732 pso_num=25 pso_serial#=109 2025-08-31 22:07:29.939306 :C7444E05:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22732 osp_idx=23 osp_ver=276896 osp_pg=0 (spawn #545851) 2025-08-31 22:07:29.939307 :C7444E06:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 22:07:29.939308 :C7444E07:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=1063701237 2025-08-31 22:07:29.939308 :C7444E08:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 22:07:29.939308 :C7444E09:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 22:07:29.939309 :C7444E0A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 22:07:29.939309 :C7444E0B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 22:07:29.939309 :C7444E0C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 22:07:29.939834 :C7444E0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 22:07:29.939884 :C7444E13:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-31 22:07:29.944254 :C7444E2A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-08-31 22:07:29.944301 :C7444E2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-08-31 22:07:29.944574 :C7444E2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-08-31 22:07:29.944592 :C7444E2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-08-31 22:07:29.944813 :C7444E2E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-08-31 22:07:29.944827 :C7444E2F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-08-31 22:07:29.945177 :C7444E30:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-08-31 22:07:29.945190 :C7444E31:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-08-31 22:07:29.945408 :C7444E32:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-08-31 22:07:29.945422 :C7444E33:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-08-31 22:07:29.945638 :C7444E34:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-08-31 22:07:29.945652 :C7444E35:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-08-31 22:07:29.948111 :C7444E68:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 22:07:30.040380 :C7444F61:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 22:07:30.027 2025-08-31 22:07:30.040381 :C7444F62:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21696 pso_num=25 pso_serial#=110 2025-08-31 22:07:30.040507 :C7444F63:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21696 osp_idx=23 osp_ver=276897 osp_pg=0 (spawn #545853) 2025-08-31 22:07:30.040508 :C7444F64:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 22:07:30.040508 :C7444F65:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=1063701330 2025-08-31 22:07:30.040509 :C7444F66:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 22:07:30.040509 :C7444F67:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 22:07:30.040510 :C7444F68:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 22:07:30.040510 :C7444F69:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 22:07:30.040511 :C7444F6A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 22:07:30.040978 :C7444F6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.047149 :C7444F8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.047190 :C7444F8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-31 22:07:30.064224 :C7444FA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.064451 :C7444FAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.064620 :C7444FB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.064775 :C7444FBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.064927 :C7444FC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.065111 :C7444FCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.065329 :C7444FD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.065475 :C7444FDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.065796 :C7444FE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.066051 :C7444FEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.066256 :C7444FF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:30.084956 :C7445007:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.698536*:C744510A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000027073AD0 estart=1756649251 eid=21989057 ctx=0x0000000777968158 cwh=0x0000000029170068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-31 22:07:31.698536*:C744510B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000027073AD0 curCtx=0x00000000292DFE88 pubTabIdxCnt=0 2025-08-31 22:07:31.698536*:C744510C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000027073AD0 2025-08-31 22:07:31.698536*:C744510D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000027073AD0 2025-08-31 22:07:31.698536*:C744510E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000270EB820 estart=1756649249 eid=16777560 ctx=0x000000077B54EAC8 cwh=0x0000000026F10608 cpu=1421875 buffg=77394 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-31 22:07:31.698536*:C744510F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000270EB820 curCtx=0x0000000029239FF0 pubTabIdxCnt=0 2025-08-31 22:07:31.698536*:C7445110:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000270EB820 2025-08-31 22:07:31.698536*:C7445111:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000270EB820 2025-08-31 22:07:31.698536*:C7445112:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000027073AD0 curCtx=0x00000000292DFE88 xsc->flg4=65792 flags=1 2025-08-31 22:07:31.698536*:C7445113:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000027073AD0 2025-08-31 22:07:31.745408*:C744514E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000270EB820 curCtx=0x0000000029239FF0 xsc->flg4=65792 flags=2 2025-08-31 22:07:31.745408*:C744514F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000270EB820 2025-08-31 22:07:31.754483 :C7445150:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.774918 :C7445172:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.776923 :C744517A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 22:07:31.777138 :C744517B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 22:07:31.778009 :C744517C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.778105 :C744517F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.778957 :C7445191:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.780418 :C7445199:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.780727 :C74451A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:31.823533*: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-31 22:07:31.823533*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-31 22:07:31.823533*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-31 22:07:31.870412*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-31 22:07:31.870412*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-31 22:07:31.870412*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-31 22:07:31.870412*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-31 22:07:31.937785 :C744526A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:33.853513 :C74452F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 22:07:33.853525 :C74452F5:db_trace:ktur.c@3079:ktuabt(): [10444:25:201] ABORT TRANSACTION - xid: 0x000a.010.0017aa03 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 21696, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de