Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250612220009\orcl_j001_9744_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:36688M/63366M, Ph+PgF:43170M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 9744, image: ORACLE.EXE (J001) *** 2025-06-12 22:00:09.095 *** SESSION ID:(209.24645) 2025-06-12 22:00:09.095 *** 2025-06-12 22:00:09.095 Process diagnostic dump for ORACLE.EXE (J001), OS id=9744, pid: 26, proc_ser: 60, sid: 209, sess_ser: 24645 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9744 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.010228 sec, exc=0.010228 sec, total=0.010228 sec wait times: max=0.500000 sec, heur=2.514956 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.502086 sec, exc=0.502086 sec, total=0.502086 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.515570 sec, exc=0.515570 sec, total=0.515570 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.487011 sec, exc=0.487011 sec, total=0.487011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 209 serial 24645 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:06 - 22:00:09] idle wait at each sample [session created at: 22:00:06] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-12 22:00:09.095 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 9744, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-12 21:59:17.070505 :A3DCBC85: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-06-12 21:59:17.071340 :A3DCBC89:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-12 21:59:18.724731 :A3DCBD21:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-12 21:59:18.716 2025-06-12 21:59:18.724733 :A3DCBD22:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17144 pso_num=26 pso_serial#=55 2025-06-12 21:59:18.724872 :A3DCBD23:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17144 (legacy spawn) 2025-06-12 21:59:18.731362 :A3DCBD24: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-06-12 21:59:18.731524 :A3DCBD29: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-06-12 21:59:18.732311 :A3DCBD2E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-12 21:59:19.370722 :A3DCBD44:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-12 21:59:19.356 2025-06-12 21:59:19.370724 :A3DCBD45:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8776 pso_num=26 pso_serial#=56 2025-06-12 21:59:19.370865 :A3DCBD46:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8776 (legacy spawn) 2025-06-12 21:59:19.377309 :A3DCBD52: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-06-12 21:59:19.377478 :A3DCBD5B: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-06-12 21:59:19.378307 :A3DCBD5C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-12 21:59:19.395238 :A3DCBD5F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-12 21:59:19.388 2025-06-12 21:59:19.395240 :A3DCBD60:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14648 pso_num=26 pso_serial#=57 2025-06-12 21:59:19.395382 :A3DCBD61:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14648 (legacy spawn) 2025-06-12 21:59:19.401911 :A3DCBD62: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-06-12 21:59:19.402085 :A3DCBD6B: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-06-12 21:59:19.402878 :A3DCBD6C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-12 21:59:22.496759 :A3DCBE4E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-12 21:59:22.483 2025-06-12 21:59:22.496761 :A3DCBE4F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17076 pso_num=26 pso_serial#=58 2025-06-12 21:59:22.496900 :A3DCBE50:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17076 (legacy spawn) 2025-06-12 21:59:22.503354 :A3DCBE51: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-06-12 21:59:22.503527 :A3DCBE56: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-06-12 21:59:22.504295 :A3DCBE5B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-12 21:59:22.512914 :A3DCBE5E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-12 21:59:22.499 2025-06-12 21:59:22.512916 :A3DCBE5F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17356 pso_num=26 pso_serial#=59 2025-06-12 21:59:22.513051 :A3DCBE60:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17356 (legacy spawn) 2025-06-12 21:59:22.519456 :A3DCBE61: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-06-12 21:59:22.519618 :A3DCBE6A: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-06-12 21:59:22.520421 :A3DCBE6B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-12 22:00:02.040156 :A3DCCC45:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-12 22:00:02.029 2025-06-12 22:00:02.040158 :A3DCCC46:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9744 pso_num=26 pso_serial#=60 2025-06-12 22:00:02.040294 :A3DCCC47:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9744 osp_idx=24 osp_ver=436875 osp_pg=0 (spawn #279135) 2025-06-12 22:00:02.040295 :A3DCCC48:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 22:00:02.040295 :A3DCCC49:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=225 time=2741345219 2025-06-12 22:00:02.040295 :A3DCCC4A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 22:00:02.040296 :A3DCCC4B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 22:00:02.040296 :A3DCCC4C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:00:02.040296 :A3DCCC4D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:00:02.040297 :A3DCCC4E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:00:02.040784 :A3DCCC52: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-06-12 22:00:02.045900 :A3DCCC9F: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-06-12 22:00:02.045941 :A3DCCCA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-06-12 22:00:02.049496 :A3DCCCBD: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-06-12 22:00:02.348268 :A3DCCFE8: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-06-12 22:00:02.391958 :A3DCD013: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-06-12 22:00:02.407585 :A3DCD01B: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-06-12 22:00:02.465152 :A3DCD023: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-06-12 22:00:02.597522 :A3DCD033: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-06-12 22:00:02.712355 :A3DCD043: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-06-12 22:00:02.721864 :A3DCD04B: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-06-12 22:00:02.736885 :A3DCD053: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-06-12 22:00:02.752072 :A3DCD067: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-06-12 22:00:02.767409 :A3DCD06F: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-06-12 22:00:02.776711 :A3DCD077: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-06-12 22:00:02.791456 :A3DCD07F: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-06-12 22:00:02.800467 :A3DCD087: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-06-12 22:00:02.815629 :A3DCD08F: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-06-12 22:00:02.825015 :A3DCD097: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-06-12 22:00:02.834120 :A3DCD09F: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-06-12 22:00:02.848741 :A3DCD0A7: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-06-12 22:00:02.857836 :A3DCD0AF: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-06-12 22:00:02.873156 :A3DCD0B7: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-06-12 22:00:02.888042 :A3DCD0BF: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-06-12 22:00:02.902760 :A3DCD0C7: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-06-12 22:00:02.917669 :A3DCD0CF: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-06-12 22:00:02.927316 :A3DCD0D7: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-06-12 22:00:02.942485 :A3DCD0F8: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-06-12 22:00:02.951977 :A3DCD11C: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-06-12 22:00:02.961808 :A3DCD13C: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-06-12 22:00:02.977162 :A3DCD16C: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-06-12 22:00:02.992508 :A3DCD1EE: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-06-12 22:00:03.000861 :A3DCD1F6: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-06-12 22:00:03.009794 :A3DCD1FE: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-06-12 22:00:03.018357 :A3DCD206: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-06-12 22:00:03.028217 :A3DCD20E: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-06-12 22:00:03.043093 :A3DCD216: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-06-12 22:00:03.057974 :A3DCD21E: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-06-12 22:00:03.067480 :A3DCD226: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-06-12 22:00:03.076364 :A3DCD22E: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-06-12 22:00:03.091358 :A3DCD236: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-06-12 22:00:03.106128 :A3DCD23E: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-06-12 22:00:03.121006 :A3DCD272: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-06-12 22:00:03.130774 :A3DCD27A: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-06-12 22:00:03.155112 :A3DCD282: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-06-12 22:00:03.164385 :A3DCD28A: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-06-12 22:00:03.173679 :A3DCD292: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-06-12 22:00:03.188983 :A3DCD29A: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-06-12 22:00:03.196432 :A3DCD2A2: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-06-12 22:00:03.203860 :A3DCD2AA: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-06-12 22:00:03.211200 :A3DCD2B2: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-06-12 22:00:03.218764 :A3DCD2BA: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-06-12 22:00:03.226330 :A3DCD2C2: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-06-12 22:00:03.233521 :A3DCD2CA: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-06-12 22:00:03.240967 :A3DCD2D2: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-06-12 22:00:03.248604 :A3DCD2DA: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-06-12 22:00:03.256513 :A3DCD2E2: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-06-12 22:00:03.264124 :A3DCD2EA: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-06-12 22:00:03.271503 :A3DCD2F2: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-06-12 22:00:03.278823*:A3DCD304:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B4D0450 estart=1749736803 eid=16785843 ctx=0x0000000787636618 cwh=0x0000000044D1F3A0 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-12 22:00:03.278823*:A3DCD305:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B4D0450 curCtx=0x0000000044D48F88 pubTabIdxCnt=0 2025-06-12 22:00:03.278823*:A3DCD306:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B4D0450 2025-06-12 22:00:03.278823*:A3DCD307:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B4D0450 2025-06-12 22:00:03.278823*:A3DCD308:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1749736801 eid=16777296 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1203125 buffg=86594 interb=28327936 r=1452 rb=26034176 w=12 wb=2293760 2025-06-12 22:00:03.278823*:A3DCD309:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000271EC288 curCtx=0x0000000044D47F80 pubTabIdxCnt=0 2025-06-12 22:00:03.278823*:A3DCD30A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000271EC288 2025-06-12 22:00:03.278823*:A3DCD30B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000271EC288 2025-06-12 22:00:03.278823*:A3DCD30C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B4D0450 curCtx=0x0000000044D48F88 xsc->flg4=65792 flags=2 2025-06-12 22:00:03.278823*:A3DCD30D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B4D0450 2025-06-12 22:00:03.279172 :A3DCD30E: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-06-12 22:00:04.387935 :A3DCD51E: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-06-12 22:00:05.278976*:A3DCD566:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000044E6F5C8 estart=1749736804 eid=16777262 ctx=0x000000078644FAD0 cwh=0x0000000044CDF0D0 cpu=46875 buffg=677 interb=0 r=0 rb=0 w=0 wb=0 2025-06-12 22:00:05.278976*:A3DCD567:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000044E6F5C8 curCtx=0x0000000044D9FF28 pubTabIdxCnt=0 2025-06-12 22:00:05.278976*:A3DCD568:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000044E6F5C8 2025-06-12 22:00:05.278976*:A3DCD569:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000044E6F5C8 2025-06-12 22:00:05.278976*:A3DCD56A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1749736801 eid=16777296 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1953125 buffg=114269 interb=27295744 r=1389 rb=25001984 w=12 wb=2293760 2025-06-12 22:00:05.278976*:A3DCD56B: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=1749736801 eid=16777296 li#=0 chi=0x00000007D7C1D128 2025-06-12 22:00:05.278976*:A3DCD56C:sql_mon_query:keswx.c@4230:keswxWriteGlobalInfoToStream(): wrote to stream flags=1551 modLen=14 modName=DBMS_SCHEDULERATIONanager.string history purgean actLen=22 actName=ORA$AT_SQ_SQL_SW_12583esholds 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-06-12 22:00:05.278976*:A3DCD56D:sql_mon_query:keswx.c@2145:keswxAddPlanMonitoring(): nbLines=0 nbVarBytes=1026 sqlid=6119441367216286794 sid=209 execStart=1749736801 execId=16777296 2025-06-12 22:00:05.278976*:A3DCD56E:sql_mon_query:keswx.c@1824:keswxAllocatePlanMemChunk(): add mem chunk to variable stream data=0x00000007DB8F10F8 buffer=0x00000007DB8F1110 size=1632 prev=0x0000000000000000 sizeVarMem=1632 2025-06-12 22:00:05.278976*:A3DCD56F:sql_mon_query:keswx.c@2353:keswxAddPlanMonitoring(): planEntry=0x00000007DB8F0FD8 added (nbEntries=14 limitEntries=640 nbPlanLines=0 nbLinesLimit=38400) 2025-06-12 22:00:05.278976*:A3DCD570:kghsc:kghsc.c@1366:kghscCopyStream(): copy src=0x00000000271213E8 dst=0x0000000027120FA0 srcOffset=1026 2025-06-12 22:00:05.278976*:A3DCD571:kghsc:kghsc.c@1128:kghscDump(begin): Dumping stream dest (@=0x0000000027120FA0 offset=0 length=0 size=1632 first=0x00000007DB8F10F8 cur=0x00000007DB8F10F8 mbs=1000 hp=0x0000000000000000 opt=18) 2025-06-12 22:00:05.278976*:A3DCD572:kghsc:kghsc.c@1147:kghscDump(end): End of dump of compact stream=0x0000000027120FA0 2025-06-12 22:00:05.278976*:A3DCD573: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-06-12 22:00:05.278976*:A3DCD574:kghsc:kghsc.c@1147:kghscDump(end): End of dump of compact stream=0x00000000271213E8 2025-06-12 22:00:05.278976*:A3DCD575:kghsc:kghsc.c@1393:kghscCopyStream(): read 1000 bytes from source stream (magic=189) 2025-06-12 22:00:05.278976*:A3DCD576:kghsc:kghsc.c@1393:kghscCopyStream(): read 26 bytes from source stream (magic=189) 2025-06-12 22:00:05.278976*:A3DCD577:kghsc:kghsc.c@1393:kghscCopyStream(): read 0 bytes from source stream (magic=189) 2025-06-12 22:00:05.278976*:A3DCD578:sql_mon_query:keswx.c@3920:keswxCurSetupPublish(): setup completed xsc=0x00000000271EC288 curCtx=0x0000000044D47F80 pubTabIdxCnt=0 2025-06-12 22:00:05.278976*:A3DCD579:sql_mon_query:keswx.c@5908:keswxCurStartPlanMonitoring(): start monitoring for xsc=0x00000000271EC288 2025-06-12 22:00:05.278976*:A3DCD57A:sql_mon_query:keswx.c@3020:keswxCurPushPlanMonitoring(begin): xsc=0x00000000271EC288 ctx=0x000000079F654AA8 li#=0 pe=0x00000007DB8F0FD8 incarnation=14 2025-06-12 22:00:05.278976*:A3DCD57B:sql_mon_query:keswx.c@3248:keswxCurPushPlanMonitoring(end): xsc=0x00000000271EC288 dis_mon=0 2025-06-12 22:00:05.278976*:A3DCD57C:sql_mon_query:keswx.c@5937:keswxCurStartPlanMonitoring(end): xsc=0x00000000271EC288 mon=1 dis=0 2025-06-12 22:00:05.278976*:A3DCD57D:sql_mon_query:keswx.c@6257:keswxCurPushMonitoring(end): monitoring started for xsc=0x00000000271EC288 2025-06-12 22:00:05.325849*:A3DCD57E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000044E6F5C8 curCtx=0x0000000044D9FF28 xsc->flg4=65792 flags=1 2025-06-12 22:00:05.325849*:A3DCD57F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000044E6F5C8 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=1 options=10264 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 0 and execution count: 0 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=10980 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=5 options=532496 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-12 22:00:06.514284*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-12 22:00:06.529906*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 0 and execution count: 0 2025-06-12 22:00:06.529906*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=10980 2025-06-12 22:00:06.529906*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-12 22:00:06.529906*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-12 22:00:06.544743 :A3DCD618: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-06-12 22:00:06.545060 :A3DCD620: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-06-12 22:00:06.552725 :A3DCD628: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-06-12 22:00:06.560146 :A3DCD630: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-06-12 22:00:06.567602 :A3DCD638: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-06-12 22:00:06.575829 :A3DCD640: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-06-12 22:00:06.576166 :A3DCD648: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-06-12 22:00:06.576166*:A3DCD64B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1749736801 eid=16777296 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1265625 buffg=220353 interb=1998848 r=120 rb=1998848 w=0 wb=0 2025-06-12 22:00:06.576166*:A3DCD64C:sql_mon_query:keswx.c@6147:keswxCurPushMonitoring(): monitoring already started for xsc=0x00000000271EC288 2025-06-12 22:00:06.576166*:A3DCD64D:sql_mon_query:keswx.c@3020:keswxCurPushPlanMonitoring(begin): xsc=0x00000000271EC288 ctx=0x000000079F654AA8 li#=0 pe=0x00000007DB8F0FD8 incarnation=14 2025-06-12 22:00:06.576166*:A3DCD64E:sql_mon_query:keswx.c@3248:keswxCurPushPlanMonitoring(end): xsc=0x00000000271EC288 dis_mon=0 2025-06-12 22:00:06.576166*:A3DCD64F:sql_mon_query:keswx.c@6257:keswxCurPushMonitoring(end): monitoring started for xsc=0x00000000271EC288 2025-06-12 22:00:06.576166*:A3DCD650:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000271EC288 curCtx=0x0000000044D47F80 xsc->flg4=67840 flags=2 2025-06-12 22:00:06.576166*:A3DCD652:sql_mon_query:keswx.c@3020:keswxCurPushPlanMonitoring(begin): xsc=0x00000000271EC288 ctx=0x000000079F654AA8 li#=0 pe=0x00000007DB8F0FD8 incarnation=14 2025-06-12 22:00:06.576166*:A3DCD655:sql_mon_query:keswx.c@3248:keswxCurPushPlanMonitoring(end): xsc=0x00000000271EC288 dis_mon=0 2025-06-12 22:00:06.576166*:A3DCD657:sql_mon_query:keswx.c@3484:keswxWriteEndInfoToStream(begin): xsc=0x00000000271EC288 curCtx=0x0000000044D47F80 endFlags=2 pxSlaveCursor=0 2025-06-12 22:00:06.576166*:A3DCD659:sql_mon_query:keswx.c@3681:keswxWriteEndInfoToStream(end): done2025-06-12 22:00:06.576166*:A3DCD65A:sql_mon_query:keswx.c@3376:keswxCurEndPlanMonitoringCb(): switching state for xsc=0x00000000271EC288 pe=0x00000007DB8F0FD8 endflag=2 flags=1 state=5 2025-06-12 22:00:06.576166*:A3DCD65B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000271EC288 2025-06-12 22:00:06.576473 :A3DCD65C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-06-12 22:00:06.578150 :A3DCD65D: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-06-12 22:00:06.581249 :A3DCD665: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-06-12 22:00:06.581335 :A3DCD668:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-12 22:00:06.581430 :A3DCD675: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 9744, J001)