Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713060006\orcl_j003_11212_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713060006\orcl_j003_11212_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:36382M/63366M, Ph+PgF:42432M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 11212, image: ORACLE.EXE (J003) *** 2025-07-13 06:00:06.311 *** SESSION ID:(233.28787) 2025-07-13 06:00:06.311 *** 2025-07-13 06:00:06.311 Process diagnostic dump for ORACLE.EXE (J003), OS id=11212, pid: 29, proc_ser: 63, sid: 233, sess_ser: 28787 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11212 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12746, hash value=611553506 Current Wait Stack: Not in wait; last wait ended 2.651910 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.651936 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.000153 sec, exc=0.000153 sec, total=0.000153 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.066436 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.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.758279 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.025494 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.000003 sec, exc=0.000003 sec, total=0.000003 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.013106 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.000064 sec, exc=0.000064 sec, total=0.000064 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.050313 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.005111 sec, exc=0.005111 sec, total=0.005111 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007914 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.000068 sec, exc=0.000068 sec, total=0.000068 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.263228 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.005013 sec, exc=0.005013 sec, total=0.005013 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000332 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.000117 sec, exc=0.000117 sec, total=0.000117 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008880 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.000073 sec of elapsed time Sampled Session History of session 233 serial 28787 --------------------------------------------------- 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, 06:00:02 - 06:00:06] not in wait at each sample [1 sample, 06:00:01] idle wait at each sample [session created at: 06:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 06:00:06.311 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 11212, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 00:04:49.950019 :B1335F1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:49.950190 :B1335F22:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:04:49.951070 :B1335F27:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:04:53.782840 :B1336092:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:04:53.768 2025-07-13 00:04:53.782842 :B1336093:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13500 pso_num=29 pso_serial#=49 2025-07-13 00:04:53.782983 :B1336094:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13500 (legacy spawn) 2025-07-13 00:04:53.789441 :B1336095:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:53.789474 :B1336098:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:53.789637 :B133609B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:04:53.790420 :B13360AC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:04:53.799029 :B13360AF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:04:53.783 2025-07-13 00:04:53.799031 :B13360B0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11784 pso_num=29 pso_serial#=50 2025-07-13 00:04:53.799202 :B13360B1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11784 (legacy spawn) 2025-07-13 00:04:53.816738 :B13360B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:53.816905 :B13360BB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:04:53.817755 :B13360BC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:04:56.916536 :B13361D2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:04:56.903 2025-07-13 00:04:56.916538 :B13361D3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15168 pso_num=29 pso_serial#=51 2025-07-13 00:04:56.916681 :B13361D4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15168 (legacy spawn) 2025-07-13 00:04:56.923411 :B13361E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:56.923582 :B13361E6:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:04:56.924440 :B13361EB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:04:56.933320 :B13361EE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:04:56.918 2025-07-13 00:04:56.933322 :B13361EF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11772 pso_num=29 pso_serial#=52 2025-07-13 00:04:56.933457 :B13361F0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11772 (legacy spawn) 2025-07-13 00:04:56.940190 :B13361F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:56.940366 :B13361FA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:04:56.941253 :B13361FB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:04:59.218628 :B1336259:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:04:59.210 2025-07-13 00:04:59.218630 :B133625A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13728 pso_num=29 pso_serial#=53 2025-07-13 00:04:59.218771 :B133625B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13728 (legacy spawn) 2025-07-13 00:04:59.225397 :B133625C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:04:59.225573 :B1336261:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:04:59.226403 :B1336266:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:06.426378 :B1337EA5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:06.412 2025-07-13 00:06:06.426380 :B1337EA6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8880 pso_num=29 pso_serial#=54 2025-07-13 00:06:06.426518 :B1337EA7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8880 (legacy spawn) 2025-07-13 00:06:06.433048 :B1337EA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:06.433222 :B1337EB1:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:06.434023 :B1337EB2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:06.442595 :B1337EB5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:06.428 2025-07-13 00:06:06.442596 :B1337EB6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15216 pso_num=29 pso_serial#=55 2025-07-13 00:06:06.442735 :B1337EB7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15216 (legacy spawn) 2025-07-13 00:06:06.461621 :B1337EB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:06.461796 :B1337EC1:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:06.462633 :B1337EC2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:07.483712 :B1337ED1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:07.475 2025-07-13 00:06:07.483714 :B1337ED2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4296 pso_num=29 pso_serial#=56 2025-07-13 00:06:07.483871 :B1337ED3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4296 (legacy spawn) 2025-07-13 00:06:07.490370 :B1337ED4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:07.490540 :B1337EDD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:07.491356 :B1337EDE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:09.124166 :B1337FA3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:09.112 2025-07-13 00:06:09.124168 :B1337FA4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9248 pso_num=29 pso_serial#=57 2025-07-13 00:06:09.124314 :B1337FA5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9248 (legacy spawn) 2025-07-13 00:06:09.130858 :B1337FB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:09.131022 :B1337FB7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:09.131821 :B1337FBC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:09.140658 :B1337FBF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:09.128 2025-07-13 00:06:09.140660 :B1337FC0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17488 pso_num=29 pso_serial#=58 2025-07-13 00:06:09.140801 :B1337FC1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17488 (legacy spawn) 2025-07-13 00:06:09.147356 :B1337FC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:09.147519 :B1337FCB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:09.148348 :B1337FCC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:09.578712 :B1337FEF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:09.566 2025-07-13 00:06:09.578714 :B1337FF0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15740 pso_num=29 pso_serial#=59 2025-07-13 00:06:09.578846 :B1337FF1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15740 (legacy spawn) 2025-07-13 00:06:09.585372 :B1337FF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:09.585547 :B1337FFB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:09.586345 :B1337FFC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 00:06:09.594929 :B1337FFF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 00:06:09.581 2025-07-13 00:06:09.594931 :B1338000:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18360 pso_num=29 pso_serial#=60 2025-07-13 00:06:09.595119 :B1338001:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18360 (legacy spawn) 2025-07-13 00:06:09.601571 :B1338002:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 00:06:09.601756 :B133800B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 00:06:09.602554 :B133800C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 01:00:08.365339 :B137B020:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 01:00:08.358 2025-07-13 01:00:08.365341 :B137B021:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16180 pso_num=29 pso_serial#=61 2025-07-13 01:00:08.365491 :B137B022:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16180 (legacy spawn) 2025-07-13 01:00:08.372040 :B137B023:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:08.372218 :B137B029:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 01:00:08.373022 :B137B02D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 01:00:16.657371 :B137B2D6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 01:00:16.652 2025-07-13 01:00:16.657373 :B137B2D7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16256 pso_num=29 pso_serial#=62 2025-07-13 01:00:16.657513 :B137B2D8:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16256 osp_idx=27 osp_ver=14341 osp_pg=0 (spawn #379580) 2025-07-13 01:00:16.657514 :B137B2D9:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 01:00:16.657515 :B137B2DA:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=157 time=1049192548 2025-07-13 01:00:16.657515 :B137B2DB:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 01:00:16.657516 :B137B2DC:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 01:00:16.657516 :B137B2DD:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 01:00:16.657516 :B137B2DE:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 01:00:16.657517 :B137B2DF:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 01:00:16.658025 :B137B2E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.658070 :B137B2E6:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 01:00:16.662458 :B137B2F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.688359 :B137B301:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286507082 8460216) 2025-07-13 01:00:16.688367 :B137B302:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.688425 :B137B30A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-13 01:00:16.688495 :B137B31A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.703828 :B137B322:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.734783 :B137B32A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.738419 :B137B332:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.742210 :B137B33A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.746487 :B137B342:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:16.747558 :B137B34A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:18.014880 :B137B396:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:18.051900 :B137B39E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:18.052223 :B137B3A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:18.052528 :B137B3AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:18.052544 :B137B3B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 01:00:18.052608 :B137B3BF:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-13 01:00:18.055716 :B137B3CB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-13 06:00:01.875332 :B14F7952:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-13 06:00:01.864 2025-07-13 06:00:01.875334 :B14F7953:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11212 pso_num=29 pso_serial#=63 2025-07-13 06:00:01.875459 :B14F7954:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11212 osp_idx=27 osp_ver=14342 osp_pg=0 (spawn #380275) 2025-07-13 06:00:01.875460 :B14F7955:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 06:00:01.875461 :B14F7956:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=158 time=1067177767 2025-07-13 06:00:01.875462 :B14F7957:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:00:01.875462 :B14F7958:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:00:01.875463 :B14F7959:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:00:01.875464 :B14F795A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:00:01.875464 :B14F795B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:00:01.875958 :B14F7965:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.880870 :B14F797C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.880905 :B14F7981:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-13 06:00:01.898352 :B14F7BA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.898499 :B14F7BAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.898524 :B14F7BB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.898682 :B14F7BCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.898835 :B14F7BD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.898991 :B14F7BDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.899136 :B14F7BE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.899283 :B14F7BEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.899427 :B14F7BF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.899576 :B14F7BFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.899798 :B14F7C03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.900050 :B14F7C0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.900252 :B14F7C13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.900400 :B14F7C1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.900545 :B14F7C23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.900693 :B14F7C2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.900846 :B14F7C33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901002 :B14F7C3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901161 :B14F7C43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901315 :B14F7C4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901461 :B14F7C53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901616 :B14F7C5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901765 :B14F7C63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.901909 :B14F7C6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.902060 :B14F7C73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.902223 :B14F7C7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.921377 :B14F7C83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:02.946982*:B14F7DF8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031233AD0 estart=1752357602 eid=20334081 ctx=0x0000000777968158 cwh=0x00000000312609C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-13 06:00:02.946982*:B14F7DFA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031233AD0 curCtx=0x000000004DF07918 pubTabIdxCnt=0 2025-07-13 06:00:02.946982*:B14F7DFC:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031233AD0 2025-07-13 06:00:02.946982*:B14F7DFE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031233AD0 2025-07-13 06:00:02.946982*:B14F7DFF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000312AB6F0 estart=1752357601 eid=16777451 ctx=0x000000077B54EAC8 cwh=0x0000000031260518 cpu=843750 buffg=47994 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-13 06:00:02.946982*:B14F7E01:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000312AB6F0 curCtx=0x000000004DDE87D0 pubTabIdxCnt=0 2025-07-13 06:00:02.946982*:B14F7E02:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000312AB6F0 2025-07-13 06:00:02.946982*:B14F7E04:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000312AB6F0 2025-07-13 06:00:02.946982*:B14F7E07:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031233AD0 curCtx=0x000000004DF07918 xsc->flg4=65792 flags=1 2025-07-13 06:00:02.946982*:B14F7E08:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031233AD0 2025-07-13 06:00:03.566488*:B14F7FC0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000312AB6F0 curCtx=0x000000004DDE87D0 xsc->flg4=65792 flags=2 2025-07-13 06:00:03.566488*:B14F7FC1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000312AB6F0 2025-07-13 06:00:03.582125 :B14F7FC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.604914 :B14F7FE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.607278 :B14F8005:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-13 06:00:03.607517 :B14F800B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-13 06:00:03.608601 :B14F8025:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.608727 :B14F802D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.609752 :B14F8035:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.611625 :B14F803D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.611961 :B14F8045:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:03.675867*: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-13 06:00:03.675867*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-13 06:00:03.675867*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-13 06:00:03.745599 :B14F8063:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:05.609015 :B14F814E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:00:05.609027 :B14F814F:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.00b.0001d292 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 11212, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de