Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525060006\orcl_j003_12132_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:37298M/63366M, Ph+PgF:44326M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 12132, image: ORACLE.EXE (J003) *** 2025-05-25 06:00:06.585 *** SESSION ID:(233.23287) 2025-05-25 06:00:06.585 *** 2025-05-25 06:00:06.585 Process diagnostic dump for ORACLE.EXE (J003), OS id=12132, pid: 29, proc_ser: 139, sid: 233, sess_ser: 23287 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12132 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12487, hash value=3655444599 Current Wait Stack: Not in wait; last wait ended 2.722969 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.722996 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000178 sec, exc=0.000178 sec, total=0.000178 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.065206 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.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.753837 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.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.025253 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.013011 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000121 sec, exc=0.000121 sec, total=0.000121 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.054873 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000065 sec, exc=0.000065 sec, total=0.000065 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007944 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.265505 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.007206 sec, exc=0.007206 sec, total=0.007206 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000171 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000129 sec, exc=0.000129 sec, total=0.000129 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007926 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000000 sec, exc=0.000000 sec, total=0.000000 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000056 sec of elapsed time Sampled Session History of session 233 serial 23287 --------------------------------------------------- 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, 06:00:02 - 06:00:05] not in wait at each sample [1 sample, 06:00:01] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 06:00:06.585 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 12132, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 00:03:39.747256 :9B9291C4: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-05-25 00:03:39.747430 :9B9291CD: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-05-25 00:03:39.748231 :9B9291CE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:03:39.756838 :9B9291D1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:03:39.743 2025-05-25 00:03:39.756841 :9B9291D2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10584 pso_num=29 pso_serial#=121 2025-05-25 00:03:39.756987 :9B9291D3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10584 (legacy spawn) 2025-05-25 00:03:39.763459 :9B9291D4: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-05-25 00:03:39.763624 :9B9291DD: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-05-25 00:03:39.764412 :9B9291DE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:03:54.158098 :9B92966C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:03:54.158 2025-05-25 00:03:54.158101 :9B92966D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8908 pso_num=29 pso_serial#=122 2025-05-25 00:03:54.158240 :9B92966E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8908 (legacy spawn) 2025-05-25 00:03:54.164602 :9B92966F: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-05-25 00:03:54.164784 :9B929674: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-05-25 00:03:54.165572 :9B929679:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:04:00.437946 :9B929861:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:04:00.430 2025-05-25 00:04:00.437948 :9B929862:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12004 pso_num=29 pso_serial#=123 2025-05-25 00:04:00.438090 :9B929863:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12004 (legacy spawn) 2025-05-25 00:04:00.444577 :9B929864: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-05-25 00:04:00.444748 :9B929869: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-05-25 00:04:00.445542 :9B92987C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:04:49.338776 :9B92A82A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:04:49.324 2025-05-25 00:04:49.338778 :9B92A82B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14568 pso_num=29 pso_serial#=124 2025-05-25 00:04:49.338914 :9B92A82C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14568 (legacy spawn) 2025-05-25 00:04:49.345336 :9B92A82D: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-05-25 00:04:49.345522 :9B92A836: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-05-25 00:04:49.346344 :9B92A837:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:04:49.354929 :9B92A83A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:04:49.340 2025-05-25 00:04:49.354931 :9B92A83B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14576 pso_num=29 pso_serial#=125 2025-05-25 00:04:49.355061 :9B92A83C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14576 (legacy spawn) 2025-05-25 00:04:49.361522 :9B92A83D: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-05-25 00:04:49.361687 :9B92A846: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-05-25 00:04:49.362472 :9B92A847:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:04:53.073722 :9B92A9B8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:04:53.069 2025-05-25 00:04:53.073724 :9B92A9B9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14656 pso_num=29 pso_serial#=126 2025-05-25 00:04:53.073858 :9B92A9BA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14656 (legacy spawn) 2025-05-25 00:04:53.080293 :9B92A9BB: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-05-25 00:04:53.080462 :9B92A9C0: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-05-25 00:04:53.081308 :9B92A9C4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:04:53.090049 :9B92A9C7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:04:53.084 2025-05-25 00:04:53.090051 :9B92A9C8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12396 pso_num=29 pso_serial#=127 2025-05-25 00:04:53.090181 :9B92A9C9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12396 (legacy spawn) 2025-05-25 00:04:53.096654 :9B92A9CA: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-05-25 00:04:53.096828 :9B92A9D3: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-05-25 00:04:53.097669 :9B92A9D4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:05:02.305415 :9B92AC98:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:05:02.291 2025-05-25 00:05:02.305417 :9B92AC99:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14896 pso_num=29 pso_serial#=128 2025-05-25 00:05:02.305554 :9B92AC9A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14896 (legacy spawn) 2025-05-25 00:05:02.311919 :9B92AC9B: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-05-25 00:05:02.312103 :9B92ACA0: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-05-25 00:05:02.312892 :9B92ACA5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:05:02.628300 :9B92ACA8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:05:02.619 2025-05-25 00:05:02.628302 :9B92ACA9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15064 pso_num=29 pso_serial#=129 2025-05-25 00:05:02.628466 :9B92ACAA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15064 (legacy spawn) 2025-05-25 00:05:02.634918 :9B92ACAB: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-05-25 00:05:02.635100 :9B92ACB0: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-05-25 00:05:02.635952 :9B92ACB5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:05:36.466866 :9B92BD0C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:05:36.458 2025-05-25 00:05:36.466868 :9B92BD0D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15308 pso_num=29 pso_serial#=130 2025-05-25 00:05:36.467005 :9B92BD0E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15308 (legacy spawn) 2025-05-25 00:05:36.473449 :9B92BD0F: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-05-25 00:05:36.551522 :9B92BD15: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-05-25 00:05:36.552434 :9B92BD1A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:05:58.038455 :9B92C568:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:05:58.036 2025-05-25 00:05:58.038457 :9B92C569:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13332 pso_num=29 pso_serial#=131 2025-05-25 00:05:58.038586 :9B92C56A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13332 (legacy spawn) 2025-05-25 00:05:58.046271 :9B92C56B: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-05-25 00:05:58.046520 :9B92C574: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-05-25 00:05:58.047264 :9B92C575:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:05:58.076269 :9B92C578:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:05:58.067 2025-05-25 00:05:58.076270 :9B92C579:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14416 pso_num=29 pso_serial#=132 2025-05-25 00:05:58.076410 :9B92C57A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14416 (legacy spawn) 2025-05-25 00:05:58.082785 :9B92C57B: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-05-25 00:05:58.082951 :9B92C584: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-05-25 00:05:58.083784 :9B92C585:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:06:02.430038 :9B92C72C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:06:02.416 2025-05-25 00:06:02.430039 :9B92C72D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13224 pso_num=29 pso_serial#=133 2025-05-25 00:06:02.430179 :9B92C72E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13224 (legacy spawn) 2025-05-25 00:06:02.437071 :9B92C72F: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-05-25 00:06:02.437245 :9B92C735: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-05-25 00:06:02.438030 :9B92C739:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:06:02.446565 :9B92C73C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:06:02.431 2025-05-25 00:06:02.446566 :9B92C73D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13500 pso_num=29 pso_serial#=134 2025-05-25 00:06:02.446699 :9B92C73E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13500 (legacy spawn) 2025-05-25 00:06:02.453117 :9B92C73F: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-05-25 00:06:02.453279 :9B92C748: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-05-25 00:06:02.454042 :9B92C749:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 00:06:04.823338 :9B92C7D7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 00:06:04.816 2025-05-25 00:06:04.823340 :9B92C7D8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14808 pso_num=29 pso_serial#=135 2025-05-25 00:06:04.823476 :9B92C7D9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14808 (legacy spawn) 2025-05-25 00:06:04.830069 :9B92C7DA: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-05-25 00:06:04.830249 :9B92C7E3: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-05-25 00:06:04.831071 :9B92C7E4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 01:00:02.336705 :9B96F819:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 01:00:02.329 2025-05-25 01:00:02.336708 :9B96F81A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14636 pso_num=29 pso_serial#=136 2025-05-25 01:00:02.336864 :9B96F81B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14636 (legacy spawn) 2025-05-25 01:00:02.343464 :9B96F81C: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-05-25 01:00:02.343649 :9B96F821: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-05-25 01:00:02.344538 :9B96F826:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 01:00:02.353234 :9B96F829:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 01:00:02.344 2025-05-25 01:00:02.353236 :9B96F82A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14928 pso_num=29 pso_serial#=137 2025-05-25 01:00:02.353375 :9B96F82B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14928 (legacy spawn) 2025-05-25 01:00:02.359843 :9B96F82C: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-05-25 01:00:02.360036 :9B96F835: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-05-25 01:00:02.360861 :9B96F836:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 01:00:12.071780 :9B96FB70:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 01:00:12.058 2025-05-25 01:00:12.071783 :9B96FB71:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14448 pso_num=29 pso_serial#=138 2025-05-25 01:00:12.071924 :9B96FB72:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14448 (legacy spawn) 2025-05-25 01:00:12.078382 :9B96FB73: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-05-25 01:00:12.078550 :9B96FB78: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-05-25 01:00:12.079346 :9B96FB7D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-25 06:00:02.044359 :9BAE4468:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-25 06:00:02.038 2025-05-25 06:00:02.044360 :9BAE4469:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12132 pso_num=29 pso_serial#=139 2025-05-25 06:00:02.044489 :9BAE4470:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12132 osp_idx=27 osp_ver=11613 osp_pg=0 (spawn #216941) 2025-05-25 06:00:02.044489 :9BAE4471:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 06:00:02.044490 :9BAE4472:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=20 time=1128574297 2025-05-25 06:00:02.044490 :9BAE4473:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 06:00:02.044491 :9BAE4474:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 06:00:02.044491 :9BAE4475:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 06:00:02.044491 :9BAE4476:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 06:00:02.044492 :9BAE4477:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 06:00:02.044974 :9BAE4478: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-05-25 06:00:02.049812 :9BAE4497: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-05-25 06:00:02.049850 :9BAE449A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-25 06:00:02.067846 :9BAE4661: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-05-25 06:00:02.068199 :9BAE4669: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-05-25 06:00:02.068360 :9BAE4671: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-05-25 06:00:02.068513 :9BAE4679: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-05-25 06:00:02.068663 :9BAE4681: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-05-25 06:00:02.068811 :9BAE4689: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-05-25 06:00:02.068967 :9BAE4691: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-05-25 06:00:02.069138 :9BAE46AC: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-05-25 06:00:02.069279 :9BAE46BE: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-05-25 06:00:02.069422 :9BAE46C6: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-05-25 06:00:02.069566 :9BAE46CE: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-05-25 06:00:02.069712 :9BAE46D6: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-05-25 06:00:02.069849 :9BAE46DE: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-05-25 06:00:02.070141 :9BAE46E6: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-05-25 06:00:02.070308 :9BAE46EE: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-05-25 06:00:02.070463 :9BAE46F6: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-05-25 06:00:02.070601 :9BAE46FE: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-05-25 06:00:02.070799 :9BAE4706: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-05-25 06:00:02.070971 :9BAE470E: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-05-25 06:00:02.071119 :9BAE4716: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-05-25 06:00:02.071262 :9BAE471E: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-05-25 06:00:02.071410 :9BAE4726: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-05-25 06:00:02.071552 :9BAE472E: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-05-25 06:00:02.071705 :9BAE4736: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-05-25 06:00:02.090637 :9BAE473E: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-05-25 06:00:03.381665*:9BAE497F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1748124003 eid=18750051 ctx=0x0000000777968158 cwh=0x00000000301B09C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-25 06:00:03.381665*:9BAE4980:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x000000003F7A7918 pubTabIdxCnt=0 2025-05-25 06:00:03.381665*:9BAE4981:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-05-25 06:00:03.381665*:9BAE4982:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-05-25 06:00:03.381665*:9BAE4983:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301FB6F0 estart=1748124001 eid=16777346 ctx=0x000000077B54EAC8 cwh=0x00000000301B0518 cpu=1125000 buffg=59641 interb=196608 r=24 rb=196608 w=0 wb=0 2025-05-25 06:00:03.381665*:9BAE4984:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301FB6F0 curCtx=0x000000003F6887D0 pubTabIdxCnt=0 2025-05-25 06:00:03.381665*:9BAE4985:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301FB6F0 2025-05-25 06:00:03.381665*:9BAE4986:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301FB6F0 2025-05-25 06:00:03.381665*:9BAE4987:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x000000003F7A7918 xsc->flg4=65792 flags=1 2025-05-25 06:00:03.381665*:9BAE4988:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-05-25 06:00:03.787923*:9BAE4AE0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301FB6F0 curCtx=0x000000003F6887D0 xsc->flg4=65792 flags=2 2025-05-25 06:00:03.787923*:9BAE4AE1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301FB6F0 2025-05-25 06:00:03.788568 :9BAE4AE2: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-05-25 06:00:03.808516 :9BAE4AEA: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-05-25 06:00:03.810462 :9BAE4AF2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-25 06:00:03.810645 :9BAE4AF3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-25 06:00:03.811518 :9BAE4AF4: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-05-25 06:00:03.811605 :9BAE4AF7: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-05-25 06:00:03.812432 :9BAE4B09: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-05-25 06:00:03.813962 :9BAE4B11: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-05-25 06:00:03.814235 :9BAE4B19: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-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-25 06:00:03.881671*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-25 06:00:03.958261 :9BAE4B2F: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-05-25 06:00:05.841885 :9BAE4C38: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-05-25 06:00:05.841896 :9BAE4C39:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.00d.0016ab39 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 12132, J003)