Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706180141\orcl_j000_18244_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706180141\orcl_j000_18244_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:37010M/63366M, Ph+PgF:43026M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 18244, image: ORACLE.EXE (J000) *** 2025-07-06 18:01:41.701 *** SESSION ID:(177.43221) 2025-07-06 18:01:41.701 *** 2025-07-06 18:01:41.701 Process diagnostic dump for ORACLE.EXE (J000), OS id=18244, pid: 22, proc_ser: 230, sid: 177, sess_ser: 43221 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18244 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12716, hash value=4291315289 Current Wait Stack: Not in wait; last wait ended 2.168665 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.168690 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.000156 sec, exc=0.000156 sec, total=0.000156 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.046209 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.754172 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025097 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012774 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.000120 sec, exc=0.000120 sec, total=0.000120 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049549 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.000759 sec, exc=0.000759 sec, total=0.000759 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007889 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.006532 sec, exc=0.006532 sec, total=0.006532 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262578 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.000140 sec, exc=0.000140 sec, total=0.000140 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000329 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.002588 sec, exc=0.002588 sec, total=0.002588 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008112 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000055 sec of elapsed time Sampled Session History of session 177 serial 43221 --------------------------------------------------- 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:01:38 - 18:01:41] not in wait at each sample [1 sample, 18:01:37] idle wait at each sample [session created at: 18:01:37] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 18:01:41.701 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 18244, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 18:00:33.070138 :AE682AAA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9276 pso_num=22 pso_serial#=221 2025-07-06 18:00:33.070294 :AE682AAB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9276 (legacy spawn) 2025-07-06 18:00:33.076858 :AE682AAC: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-07-06 18:00:33.077059 :AE682AB5: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-07-06 18:00:33.077931 :AE682AB6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:00:47.243235 :AE682F2A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:00:47.237 2025-07-06 18:00:47.243237 :AE682F2B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10656 pso_num=22 pso_serial#=222 2025-07-06 18:00:47.243376 :AE682F2C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10656 osp_idx=0 osp_ver=376164 osp_pg=0 (spawn #358617) 2025-07-06 18:00:47.243377 :AE682F2D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 18:00:47.243377 :AE682F2E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=78 time=505623142 2025-07-06 18:00:47.243377 :AE682F2F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 18:00:47.243378 :AE682F30:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 18:00:47.243378 :AE682F31:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 18:00:47.243378 :AE682F32:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 18:00:47.243378 :AE682F33:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 18:00:47.243882 :AE682F34: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-07-06 18:00:47.244561 :AE682F49: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-07-06 18:00:47.255901 :AE682F65:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-06 18:00:47.256370 :AE682F66:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-06 18:00:47.258943 :AE682F67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.259289 :AE682F68: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-07-06 18:00:47.259326 :AE682F6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.262526 :AE682F71: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-07-06 18:00:47.263294 :AE682F79: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-07-06 18:00:47.264828 :AE682F81: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-07-06 18:00:47.269028 :AE682F89: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-07-06 18:00:47.272530 :AE682F91: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-07-06 18:00:47.276483 :AE682F99: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-07-06 18:00:47.280439 :AE682FA1: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-07-06 18:00:47.281037 :AE682FA9: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-07-06 18:00:47.286262 :AE682FBD: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-07-06 18:00:47.286526 :AE682FC5: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-07-06 18:00:47.290535 :AE682FCD: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-07-06 18:00:47.291013 :AE682FD5: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-07-06 18:00:47.294486 :AE682FDD: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-07-06 18:00:47.295452 :AE682FE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.295717 :AE682FE6: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-07-06 18:00:47.295757 :AE682FE9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.295769 :AE682FEA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.295946 :AE682FF0: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-07-06 18:00:47.295964 :AE682FF3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.295970 :AE682FF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.296071 :AE682FFA: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-07-06 18:00:47.296087 :AE682FFD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.296092 :AE682FFE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.296213 :AE683004: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-07-06 18:00:47.296230 :AE683007:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.296237 :AE683008:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.296338 :AE68300E: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-07-06 18:00:47.296354 :AE683011:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.296360 :AE683012:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.296453 :AE683018: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-07-06 18:00:47.296468 :AE68301B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.297000 :AE683021:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.297117 :AE683022: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-07-06 18:00:47.297136 :AE683025:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.301153 :AE68302B: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-07-06 18:00:47.301230 :AE68302E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:00:47.301477 :AE683034: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-07-06 18:00:47.301499 :AE683037:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:00:47.303704 :AE68303D: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-07-06 18:00:47.303851 :AE683046: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-07-06 18:01:17.378267 :AE683D30:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:29.339556 :AE6840CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:29.330 2025-07-06 18:01:29.339558 :AE6840CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15856 pso_num=22 pso_serial#=223 2025-07-06 18:01:29.339717 :AE6840CC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15856 (legacy spawn) 2025-07-06 18:01:29.346491 :AE6840CD: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-07-06 18:01:29.346684 :AE6840D6: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-07-06 18:01:29.347499 :AE6840D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:32.383909 :AE6841CD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:32.370 2025-07-06 18:01:32.383911 :AE6841CE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17400 pso_num=22 pso_serial#=224 2025-07-06 18:01:32.384045 :AE6841CF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17400 (legacy spawn) 2025-07-06 18:01:32.390723 :AE6841D0: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-07-06 18:01:32.390909 :AE6841D8: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-07-06 18:01:32.391709 :AE6841DA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:32.400648 :AE6841E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:32.401 2025-07-06 18:01:32.400649 :AE6841EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14676 pso_num=22 pso_serial#=225 2025-07-06 18:01:32.400794 :AE6841EB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14676 (legacy spawn) 2025-07-06 18:01:32.407394 :AE6841EC: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-07-06 18:01:32.407558 :AE6841F5: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-07-06 18:01:32.408375 :AE6841F6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:35.222292 :AE6842CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:35.214 2025-07-06 18:01:35.222294 :AE6842CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10808 pso_num=22 pso_serial#=226 2025-07-06 18:01:35.222431 :AE6842CC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10808 (legacy spawn) 2025-07-06 18:01:35.228994 :AE6842CD: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-07-06 18:01:35.229162 :AE6842D6: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-07-06 18:01:35.230010 :AE6842D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:35.712798 :AE684319:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:35.699 2025-07-06 18:01:35.712801 :AE68431A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9528 pso_num=22 pso_serial#=227 2025-07-06 18:01:35.712939 :AE68431B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9528 (legacy spawn) 2025-07-06 18:01:35.719638 :AE68431C: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-07-06 18:01:35.719821 :AE684325: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-07-06 18:01:35.720718 :AE684326:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:35.729699 :AE684329:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:35.715 2025-07-06 18:01:35.729701 :AE68432A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9900 pso_num=22 pso_serial#=228 2025-07-06 18:01:35.729875 :AE68432B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9900 (legacy spawn) 2025-07-06 18:01:35.736652 :AE68432C: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-07-06 18:01:35.736831 :AE684335: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-07-06 18:01:35.737671 :AE684336:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:37.626390 :AE6843D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:37.621 2025-07-06 18:01:37.626392 :AE6843D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16148 pso_num=22 pso_serial#=229 2025-07-06 18:01:37.626523 :AE6843DA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16148 osp_idx=0 osp_ver=376171 osp_pg=0 (spawn #358619) 2025-07-06 18:01:37.626525 :AE6843DB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 18:01:37.626525 :AE6843DC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=79 time=505673517 2025-07-06 18:01:37.626525 :AE6843DD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 18:01:37.626526 :AE6843DE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 18:01:37.626526 :AE6843DF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 18:01:37.626526 :AE6843E0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 18:01:37.626527 :AE6843E1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 18:01:37.627037 :AE6843E2: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-07-06 18:01:37.627082 :AE6843E8: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-07-06 18:01:37.631332 :AE6843FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-06 18:01:37.631376 :AE6843FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-06 18:01:37.631631 :AE6843FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-06 18:01:37.631647 :AE6843FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-06 18:01:37.631862 :AE6843FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-06 18:01:37.631879 :AE6843FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-06 18:01:37.632191 :AE684400:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-06 18:01:37.632204 :AE684401:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-06 18:01:37.632416 :AE684402:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-06 18:01:37.632429 :AE684403:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-06 18:01:37.632639 :AE684404:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-06 18:01:37.632652 :AE684405:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-06 18:01:37.635338 :AE684433:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-06 18:01:37.723713 :AE684520:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-06 18:01:37.715 2025-07-06 18:01:37.723716 :AE684521:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18244 pso_num=22 pso_serial#=230 2025-07-06 18:01:37.724011 :AE684522:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18244 osp_idx=0 osp_ver=376172 osp_pg=0 (spawn #358621) 2025-07-06 18:01:37.724012 :AE684523:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 18:01:37.724013 :AE684524:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=80 time=505673611 2025-07-06 18:01:37.724013 :AE684525:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 18:01:37.724014 :AE684526:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 18:01:37.724014 :AE684527:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 18:01:37.724014 :AE684528:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 18:01:37.724015 :AE684529:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 18:01:37.724722 :AE68452A: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-07-06 18:01:37.729990 :AE684549: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-07-06 18:01:37.730039 :AE68454C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-07-06 18:01:37.747326 :AE684562: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-07-06 18:01:37.747526 :AE68456A: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-07-06 18:01:37.747696 :AE684572: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-07-06 18:01:37.747910 :AE68457A: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-07-06 18:01:37.748131 :AE684582: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-07-06 18:01:37.748281 :AE68458A: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-07-06 18:01:37.748612 :AE684592: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-07-06 18:01:37.748872 :AE68459A: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-07-06 18:01:37.767775 :AE6845A2: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-07-06 18:01:39.346900*:AE684686:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000023863AD0 estart=1751796098 eid=20158965 ctx=0x0000000777968158 cwh=0x00000000299A0068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-06 18:01:39.346900*:AE684687:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000023863AD0 curCtx=0x0000000029A619A8 pubTabIdxCnt=0 2025-07-06 18:01:39.346900*:AE684688:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000023863AD0 2025-07-06 18:01:39.346900*:AE684689:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000023863AD0 2025-07-06 18:01:39.346900*:AE68468A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000238DB820 estart=1751796097 eid=16777439 ctx=0x000000077B54EAC8 cwh=0x000000000FC00608 cpu=1343750 buffg=72991 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-06 18:01:39.346900*:AE68468B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000238DB820 curCtx=0x0000000029A69FF0 pubTabIdxCnt=0 2025-07-06 18:01:39.346900*:AE68468C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000238DB820 2025-07-06 18:01:39.346900*:AE68468D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000238DB820 2025-07-06 18:01:39.346900*:AE68468E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000023863AD0 curCtx=0x0000000029A619A8 xsc->flg4=65792 flags=1 2025-07-06 18:01:39.346900*:AE68468F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000023863AD0 2025-07-06 18:01:39.471897*:AE684753:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000238DB820 curCtx=0x0000000029A69FF0 xsc->flg4=65792 flags=2 2025-07-06 18:01:39.471897*:AE684754:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000238DB820 2025-07-06 18:01:39.481388 :AE684755: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-07-06 18:01:39.502053 :AE68476E: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-07-06 18:01:39.503969 :AE684776:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-06 18:01:39.504169 :AE684777:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-06 18:01:39.505113 :AE684778: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-07-06 18:01:39.505204 :AE68477B: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-07-06 18:01:39.506086 :AE68478D: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-07-06 18:01:39.507588 :AE684795: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-07-06 18:01:39.507864 :AE68479D: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-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-06 18:01:39.550020*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-06 18:01:39.618046 :AE6847A5: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-07-06 18:01:41.509495 :AE68488A: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-07-06 18:01:41.509510 :AE68488B:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x000a.006.001718c2 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 18244, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de