Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250513220008\orcl_j001_7912_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:37381M/63366M, Ph+PgF:44649M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 7912, image: ORACLE.EXE (J001) *** 2025-05-13 22:00:08.940 *** SESSION ID:(209.283) 2025-05-13 22:00:08.940 *** 2025-05-13 22:00:08.940 Process diagnostic dump for ORACLE.EXE (J001), OS id=7912, pid: 26, proc_ser: 96, sid: 209, sess_ser: 283 ------------------------------------------------------------------------------- current sql: /* SQL Analyze(209,1) */ SELECT rs.* FROM ( SELECT ROWNUM rn, temp.* FROM (SELECT b.* FROM BookInfo b WHERE 1 = 1) temp ) rs WHERE rs.rn >= 1 and rs.rn <= 30 client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 7912 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_SQ_SQL_SW_12429, hash value=197136845 Current Wait Stack: Not in wait; last wait ended 0.942588 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.942612 sec since last wait 0: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3451 seq_num=3452 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.078374 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3450 seq_num=3451 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.080512 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3449 seq_num=3450 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.088360 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3448 seq_num=3449 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.092819 sec of elapsed time 4: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3447 seq_num=3448 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.086555 sec of elapsed time 5: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3446 seq_num=3447 snap_id=1 wait times: snap=0.000010 sec, exc=0.000010 sec, total=0.000010 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.090717 sec of elapsed time 6: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3445 seq_num=3446 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.092850 sec of elapsed time 7: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3444 seq_num=3445 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.097267 sec of elapsed time 8: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3443 seq_num=3444 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.095995 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x1000 wait_id=3442 seq_num=3443 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.270969 sec of elapsed time Sampled Session History of session 209 serial 283 --------------------------------------------------- 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, 22:00:05 - 22:00:08] not in wait at each sample [1 sample, 22:00:04] waited for 'control file sequential read', seq_num: 2622 p1: 'file#'=0x0 p2: 'block#'=0x10 p3: 'blocks'=0x1 time_waited: 0.000046 sec (sample interval: 0 sec) [2 samples, 22:00:02 - 22:00:03] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'control file sequential read' [1 sample, 22:00:04 ] time_waited: 0.000046 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-13 22:00:08.940 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 7912, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-13 21:59:34.285840 :96B12BDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 21:59:34.286038 :96B12BDF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-13 21:59:34.286872 :96B12BE4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-13 21:59:47.067641 :96B12FED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-13 21:59:47.053 2025-05-13 21:59:47.067643 :96B12FEE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11896 pso_num=26 pso_serial#=92 2025-05-13 21:59:47.067825 :96B12FEF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11896 (legacy spawn) 2025-05-13 21:59:47.074285 :96B12FF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 21:59:47.074461 :96B12FF5:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-13 21:59:47.075324 :96B12FFA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-13 21:59:47.083991 :96B12FFD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-13 21:59:47.069 2025-05-13 21:59:47.083993 :96B12FFE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3448 pso_num=26 pso_serial#=93 2025-05-13 21:59:47.084144 :96B12FFF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=3448 (legacy spawn) 2025-05-13 21:59:47.090506 :96B13000:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 21:59:47.090672 :96B13009:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-13 21:59:47.091460 :96B1300A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-13 21:59:50.232806 :96B13114:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-13 21:59:50.220 2025-05-13 21:59:50.232808 :96B13115:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12888 pso_num=26 pso_serial#=94 2025-05-13 21:59:50.232949 :96B13116:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12888 (legacy spawn) 2025-05-13 21:59:50.239438 :96B13117:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 21:59:50.239602 :96B1311C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-13 21:59:50.240401 :96B13121:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-13 21:59:50.249283 :96B13124:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-13 21:59:50.235 2025-05-13 21:59:50.249285 :96B13125:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11628 pso_num=26 pso_serial#=95 2025-05-13 21:59:50.249418 :96B13126:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11628 (legacy spawn) 2025-05-13 21:59:50.255905 :96B13127:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 21:59:50.256088 :96B13130:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-13 21:59:50.256902 :96B13131:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-13 22:00:02.142688 :96B13644:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-13 22:00:02.131 2025-05-13 22:00:02.142690 :96B13645:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7912 pso_num=26 pso_serial#=96 2025-05-13 22:00:02.142819 :96B13646:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7912 osp_idx=24 osp_ver=261215 osp_pg=0 (spawn #179155) 2025-05-13 22:00:02.142820 :96B13647:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 22:00:02.142820 :96B13648:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=171 time=149412000 2025-05-13 22:00:02.142820 :96B13649:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 22:00:02.142821 :96B1364A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 22:00:02.142821 :96B1364B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 22:00:02.142821 :96B1364C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 22:00:02.142822 :96B1364D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 22:00:02.143288 :96B13652:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.148445 :96B13676:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.148480 :96B13679:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-13 22:00:02.151757 :96B13694:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.531598 :96B139EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.576441 :96B13A06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.593344 :96B13A0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.652736 :96B13A1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.785730 :96B13A2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.900296 :96B13A36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.909535 :96B13A3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.924092 :96B13A46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.938406 :96B13A4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.953229 :96B13A56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.962433 :96B13A5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.976524 :96B13A66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.985736 :96B13A6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.000290 :96B13A76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.009582 :96B13A7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.018735 :96B13A86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.032979 :96B13A8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.041867 :96B13A96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.056679 :96B13A9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.071468 :96B13AA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.086379 :96B13AAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.094250 :96B13AB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.108820 :96B13ABE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.118198 :96B13AC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.132643 :96B13ACE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.142412 :96B13AD6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.151024 :96B13ADE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.165625 :96B13AE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.174533 :96B13AEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.189075 :96B13AF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.197540 :96B13AFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.207183 :96B13B06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.221802 :96B13B0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.236235 :96B13B16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.243663 :96B13B1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.253021 :96B13B26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.262127 :96B13B2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.276693 :96B13B36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.290834 :96B13B3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.305222 :96B13B46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.314061 :96B13B4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.323786 :96B13B56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.332870 :96B13B5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.341734 :96B13B66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.356177 :96B13B6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.365367 :96B13B76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.372618 :96B13B8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.379701 :96B13B92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.386723 :96B13B9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.394334 :96B13BA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.401590 :96B13BAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.408384 :96B13BB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.415472 :96B13BBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.423122 :96B13BC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.430373 :96B13BCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.437320 :96B13BD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.444594 :96B13BDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.451896 :96B13BE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.459412 :96B13BEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.466755 :96B13BF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.473938 :96B13BFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.481506 :96B13C02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.615824*:96B13C0A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029216918 estart=1747144802 eid=16777317 ctx=0x00000007831EA738 cwh=0x0000000029240248 cpu=140625 buffg=37091 interb=114688 r=7 rb=114688 w=0 wb=0 2025-05-13 22:00:03.615824*:96B13C0B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029216918 curCtx=0x000000003BEFC1B0 pubTabIdxCnt=0 2025-05-13 22:00:03.615824*:96B13C0C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029216918 2025-05-13 22:00:03.615824*:96B13C0D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029216918 2025-05-13 22:00:03.615824*:96B13C0E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1747144801 eid=16777266 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1421875 buffg=126329 interb=31670272 r=1655 rb=29368320 w=12 wb=2301952 2025-05-13 22:00:03.615824*:96B13C0F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000271EC288 curCtx=0x000000003C001D78 pubTabIdxCnt=0 2025-05-13 22:00:03.615824*:96B13C10:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000271EC288 2025-05-13 22:00:03.615824*:96B13C11:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000271EC288 2025-05-13 22:00:04.022258*:96B13D44:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029216918 curCtx=0x000000003BEFC1B0 xsc->flg4=65792 flags=1 2025-05-13 22:00:04.022258*:96B13D45:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029216918 2025-05-13 22:00:04.552989 :96B13E09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:05.627497*:96B13EF2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003BDD7C98 estart=1747144804 eid=16777244 ctx=0x00000007868F87F0 cwh=0x000000003BEE90C8 cpu=31250 buffg=628 interb=0 r=0 rb=0 w=0 wb=0 2025-05-13 22:00:05.627497*:96B13EF3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003BDD7C98 curCtx=0x000000003BDEFF28 pubTabIdxCnt=0 2025-05-13 22:00:05.627497*:96B13EF4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003BDD7C98 2025-05-13 22:00:05.627497*:96B13EF5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003BDD7C98 2025-05-13 22:00:05.627497*:96B13EF6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1747144801 eid=16777266 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1968750 buffg=80908 interb=25362432 r=1270 rb=23060480 w=12 wb=2301952 2025-05-13 22:00:05.627497*:96B13EF7:sql_mon_query:keswx.c@5747:keswxCurStartPlanMonitoring(begin): xsc=0x00000000271EC288 qc_sid=0 sid=209 sgrp#=0 sset#=0 sv#=65535 sqlid=6119441367216286794 phv=0 estart=1747144801 eid=16777266 li#=0 chi=0x00000007D7C1D128 2025-05-13 22:00:05.627497*:96B13EF8:sql_mon_query:keswx.c@4230:keswxWriteGlobalInfoToStream(): wrote to stream flags=1551 modLen=14 modName=DBMS_SCHEDULERntIONanager.current metric purgean actLen=22 actName=ORA$AT_SQ_SQL_SW_12429esholds userid=0 userLen=3 userName=SYS svcLen=0 svcName=SYS$USERS clidLen=0 clid=(null) progLen=17 progName=ORACLE.EXE (J001) plseoid=0 plsesid=0 plscoid=0 plscsid=0 txtLen=959 txt=DECLARE job BINARY_INTEGER := :job; next_date TIMESTAMP WITH TIME ZONE := :mydate; broken BOOLEAN := FALSE; job_name VARCHAR2(30) := :job_name; job_subname VARCHAR2(30) := :job_subname; job_owner VARCHAR2(30) := :job_owner; job_start TIMESTAMP WITH T 2025-05-13 22:00:05.627497*:96B13EF9:sql_mon_query:keswx.c@2145:keswxAddPlanMonitoring(): nbLines=0 nbVarBytes=1026 sqlid=6119441367216286794 sid=209 execStart=1747144801 execId=16777266 2025-05-13 22:00:05.627497*:96B13EFA:sql_mon_query:keswx.c@1824:keswxAllocatePlanMemChunk(): add mem chunk to variable stream data=0x00000007DBAD1900 buffer=0x00000007DBAD1918 size=1632 prev=0x0000000000000000 sizeVarMem=1632 2025-05-13 22:00:05.627497*:96B13EFB:sql_mon_query:keswx.c@2353:keswxAddPlanMonitoring(): planEntry=0x00000007DBAD17E0 added (nbEntries=11 limitEntries=640 nbPlanLines=0 nbLinesLimit=38400) 2025-05-13 22:00:05.627497*:96B13EFC:kghsc:kghsc.c@1366:kghscCopyStream(): copy src=0x00000000271213E8 dst=0x0000000027120FA0 srcOffset=1026 2025-05-13 22:00:05.627497*:96B13EFD:kghsc:kghsc.c@1128:kghscDump(begin): Dumping stream dest (@=0x0000000027120FA0 offset=0 length=0 size=1632 first=0x00000007DBAD1900 cur=0x00000007DBAD1900 mbs=1000 hp=0x0000000000000000 opt=18) 2025-05-13 22:00:05.627497*:96B13EFE:kghsc:kghsc.c@1147:kghscDump(end): End of dump of compact stream=0x0000000027120FA0 2025-05-13 22:00:05.627497*:96B13EFF:kghsc:kghsc.c@1128:kghscDump(begin): Dumping stream src (@=0x00000000271213E8 offset=0 length=1026 size=2000 first=0x0000000027121818 cur=0x0000000027121818 mbs=1000 hp=0x0000000027190328 opt=57) 2025-05-13 22:00:05.627497*:96B13F00:kghsc:kghsc.c@1147:kghscDump(end): End of dump of compact stream=0x00000000271213E8 2025-05-13 22:00:05.627497*:96B13F01:kghsc:kghsc.c@1393:kghscCopyStream(): read 1000 bytes from source stream (magic=189) 2025-05-13 22:00:05.627497*:96B13F02:kghsc:kghsc.c@1393:kghscCopyStream(): read 26 bytes from source stream (magic=189) 2025-05-13 22:00:05.627497*:96B13F03:kghsc:kghsc.c@1393:kghscCopyStream(): read 0 bytes from source stream (magic=189) 2025-05-13 22:00:05.627497*:96B13F04:sql_mon_query:keswx.c@3920:keswxCurSetupPublish(): setup completed xsc=0x00000000271EC288 curCtx=0x000000003C001D78 pubTabIdxCnt=0 2025-05-13 22:00:05.627497*:96B13F05:sql_mon_query:keswx.c@5908:keswxCurStartPlanMonitoring(): start monitoring for xsc=0x00000000271EC288 2025-05-13 22:00:05.627497*:96B13F06:sql_mon_query:keswx.c@3020:keswxCurPushPlanMonitoring(begin): xsc=0x00000000271EC288 ctx=0x000000079F654AA8 li#=0 pe=0x00000007DBAD17E0 incarnation=11 2025-05-13 22:00:05.627497*:96B13F07:sql_mon_query:keswx.c@3248:keswxCurPushPlanMonitoring(end): xsc=0x00000000271EC288 dis_mon=0 2025-05-13 22:00:05.627497*:96B13F08:sql_mon_query:keswx.c@5937:keswxCurStartPlanMonitoring(end): xsc=0x00000000271EC288 mon=1 dis=0 2025-05-13 22:00:05.627497*:96B13F09:sql_mon_query:keswx.c@6257:keswxCurPushMonitoring(end): monitoring started for xsc=0x00000000271EC288 2025-05-13 22:00:05.674369*:96B13F0A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003BDD7C98 curCtx=0x000000003BDEFF28 xsc->flg4=65792 flags=1 2025-05-13 22:00:05.674369*:96B13F0B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003BDD7C98 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=1 options=10264 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 0 and execution count: 0 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=10980 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=5 options=532496 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-13 22:00:06.830812*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-13 22:00:06.846431*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 0 and execution count: 0 2025-05-13 22:00:06.846431*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=10980 2025-05-13 22:00:06.846431*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-13 22:00:06.846431*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-13 22:00:06.858932 :96B13FAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.859262 :96B13FB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.866758 :96B13FBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.873919 :96B13FC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.881285 :96B13FCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.889766 :96B13FD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.892226 :96B13FDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.892476 :96B13FE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.900046 :96B13FEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.907271 :96B13FF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.914694 :96B13FFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.922186 :96B14003:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:06.930517 :96B1400B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:07.627832*:96B14029:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000291C3F88 estart=1747144806 eid=16777239 ctx=0x000000077A535040 cwh=0x00000000291D2E10 cpu=46875 buffg=822 interb=0 r=0 rb=0 w=0 wb=0 2025-05-13 22:00:07.627832*:96B1402A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000291C3F88 curCtx=0x000000003BDF6E18 pubTabIdxCnt=0 2025-05-13 22:00:07.627832*:96B1402B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000291C3F88 2025-05-13 22:00:07.627832*:96B1402C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000291C3F88 2025-05-13 22:00:07.627832*:96B1402D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1747144801 eid=16777266 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1968750 buffg=236629 interb=4243456 r=259 rb=4243456 w=0 wb=0 2025-05-13 22:00:07.627832*:96B1402E:sql_mon_query:keswx.c@6147:keswxCurPushMonitoring(): monitoring already started for xsc=0x00000000271EC288 2025-05-13 22:00:07.627832*:96B1402F:sql_mon_query:keswx.c@3020:keswxCurPushPlanMonitoring(begin): xsc=0x00000000271EC288 ctx=0x000000079F654AA8 li#=0 pe=0x00000007DBAD17E0 incarnation=11 2025-05-13 22:00:07.627832*:96B14030:sql_mon_query:keswx.c@3248:keswxCurPushPlanMonitoring(end): xsc=0x00000000271EC288 dis_mon=0 2025-05-13 22:00:07.627832*:96B14031:sql_mon_query:keswx.c@6257:keswxCurPushMonitoring(end): monitoring started for xsc=0x00000000271EC288 2025-05-13 22:00:07.659081*:96B14032:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000291C3F88 curCtx=0x000000003BDF6E18 xsc->flg4=65792 flags=1 2025-05-13 22:00:07.659081*:96B14033:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000291C3F88 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 7912, J001)