Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250602220006\orcl_j001_7776_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:37130M/63366M, Ph+PgF:43971M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 7776, image: ORACLE.EXE (J001) *** 2025-06-02 22:00:06.275 *** SESSION ID:(209.31751) 2025-06-02 22:00:06.275 *** 2025-06-02 22:00:06.275 Process diagnostic dump for ORACLE.EXE (J001), OS id=7776, pid: 26, proc_ser: 104, sid: 209, sess_ser: 31751 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 7776 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.307606 sec, exc=0.307606 sec, total=0.307606 sec wait times: max=0.500000 sec, heur=1.805012 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=0 seq_num=1 snap_id=1 wait times: snap=0.497380 sec, exc=0.497380 sec, total=0.497380 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 31751 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-02 22:00:06.275 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 7776, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-02 21:57:22.344550 :9F7AFDD4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:57:30.596033 :9F7B006F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:57:30.583 2025-06-02 21:57:30.596035 :9F7B0070:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14764 pso_num=26 pso_serial#=89 2025-06-02 21:57:30.596217 :9F7B0071:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14764 (legacy spawn) 2025-06-02 21:57:30.602728 :9F7B0072: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-02 21:57:30.602910 :9F7B0077: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-02 21:57:30.603746 :9F7B007C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:58:24.471987 :9F7B118E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:58:24.459 2025-06-02 21:58:24.471989 :9F7B118F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14484 pso_num=26 pso_serial#=90 2025-06-02 21:58:24.472164 :9F7B1190:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14484 (legacy spawn) 2025-06-02 21:58:24.478598 :9F7B1191: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-02 21:58:24.478787 :9F7B1198: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-02 21:58:24.479647 :9F7B119B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:58:27.359315 :9F7B1284:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:58:27.344 2025-06-02 21:58:27.359316 :9F7B1285:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11644 pso_num=26 pso_serial#=91 2025-06-02 21:58:27.359456 :9F7B1286:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11644 (legacy spawn) 2025-06-02 21:58:27.366152 :9F7B1287: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-02 21:58:27.366353 :9F7B128D: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-02 21:58:27.367198 :9F7B1291:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:58:27.376307 :9F7B1294:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:58:27.376 2025-06-02 21:58:27.376309 :9F7B1295:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13676 pso_num=26 pso_serial#=92 2025-06-02 21:58:27.376454 :9F7B1296:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13676 (legacy spawn) 2025-06-02 21:58:27.383222 :9F7B1297: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-02 21:58:27.383409 :9F7B12A0: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-02 21:58:27.384310 :9F7B12A1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:58:30.539667 :9F7B1391:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:58:30.526 2025-06-02 21:58:30.539669 :9F7B1392:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8672 pso_num=26 pso_serial#=93 2025-06-02 21:58:30.539807 :9F7B1393:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8672 (legacy spawn) 2025-06-02 21:58:30.559340 :9F7B1394: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-02 21:58:30.559535 :9F7B139C: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-02 21:58:30.560432 :9F7B139D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:58:30.569594 :9F7B13A0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:58:30.558 2025-06-02 21:58:30.569596 :9F7B13A1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14676 pso_num=26 pso_serial#=94 2025-06-02 21:58:30.569747 :9F7B13A2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14676 (legacy spawn) 2025-06-02 21:58:30.576549 :9F7B13A3: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-02 21:58:30.576741 :9F7B13AA: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-02 21:58:30.577653 :9F7B13AD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:58:38.784013 :9F7B1618:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:58:38.775 2025-06-02 21:58:38.784015 :9F7B1619:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15024 pso_num=26 pso_serial#=95 2025-06-02 21:58:38.784151 :9F7B161A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15024 (legacy spawn) 2025-06-02 21:58:38.790588 :9F7B161B: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-02 21:58:38.790766 :9F7B1624: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-02 21:58:38.791558 :9F7B1625:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:26.635659 :9F7B2614:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:26.622 2025-06-02 21:59:26.635661 :9F7B2615:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9988 pso_num=26 pso_serial#=96 2025-06-02 21:59:26.635800 :9F7B2616:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9988 (legacy spawn) 2025-06-02 21:59:26.656518 :9F7B2617: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-02 21:59:26.656695 :9F7B261F: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-02 21:59:26.657519 :9F7B2621:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:39.942352 :9F7B2A24:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:39.928 2025-06-02 21:59:39.942354 :9F7B2A25:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15120 pso_num=26 pso_serial#=97 2025-06-02 21:59:39.942491 :9F7B2A26:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15120 (legacy spawn) 2025-06-02 21:59:39.949076 :9F7B2A27: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-02 21:59:39.949247 :9F7B2A2D: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-02 21:59:39.950096 :9F7B2A31:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:39.958903 :9F7B2A34:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:39.944 2025-06-02 21:59:39.958905 :9F7B2A35:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3448 pso_num=26 pso_serial#=98 2025-06-02 21:59:39.959048 :9F7B2A36:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=3448 (legacy spawn) 2025-06-02 21:59:39.965494 :9F7B2A37: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-02 21:59:39.965657 :9F7B2A40: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-02 21:59:39.966464 :9F7B2A41:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:42.992540 :9F7B2B42:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:42.980 2025-06-02 21:59:42.992542 :9F7B2B43:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15292 pso_num=26 pso_serial#=99 2025-06-02 21:59:42.992685 :9F7B2B44:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15292 (legacy spawn) 2025-06-02 21:59:43.014778 :9F7B2B45: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-02 21:59:43.014808 :9F7B2B48: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-02 21:59:43.014945 :9F7B2B4B: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-02 21:59:43.015730 :9F7B2B5C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:43.024413 :9F7B2B5F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:43.011 2025-06-02 21:59:43.024415 :9F7B2B60:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12772 pso_num=26 pso_serial#=100 2025-06-02 21:59:43.024552 :9F7B2B61:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12772 (legacy spawn) 2025-06-02 21:59:43.031054 :9F7B2B62: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-02 21:59:43.031220 :9F7B2B6B: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-02 21:59:43.032004 :9F7B2B6C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:44.740257 :9F7B2BE1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:44.727 2025-06-02 21:59:44.740259 :9F7B2BE2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13636 pso_num=26 pso_serial#=101 2025-06-02 21:59:44.740398 :9F7B2BE3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13636 (legacy spawn) 2025-06-02 21:59:44.746840 :9F7B2BE4: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-02 21:59:44.747021 :9F7B2BED: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-02 21:59:44.747818 :9F7B2BEE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:44.756529 :9F7B2BF1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:44.742 2025-06-02 21:59:44.756531 :9F7B2BF2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13360 pso_num=26 pso_serial#=102 2025-06-02 21:59:44.756664 :9F7B2BF3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13360 (legacy spawn) 2025-06-02 21:59:44.763128 :9F7B2BF4: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-02 21:59:44.763294 :9F7B2BFD: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-02 21:59:44.764076 :9F7B2BFE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 21:59:46.909741 :9F7B2CB1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 21:59:46.899 2025-06-02 21:59:46.909743 :9F7B2CB2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15300 pso_num=26 pso_serial#=103 2025-06-02 21:59:46.909879 :9F7B2CB3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15300 (legacy spawn) 2025-06-02 21:59:46.916406 :9F7B2CB4: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-02 21:59:46.916571 :9F7B2CB9: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-02 21:59:46.917403 :9F7B2CBE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-02 22:00:02.062456 :9F7B3308:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-02 22:00:02.061 2025-06-02 22:00:02.062458 :9F7B3309:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7776 pso_num=26 pso_serial#=104 2025-06-02 22:00:02.062591 :9F7B330A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7776 osp_idx=24 osp_ver=374954 osp_pg=0 (spawn #245826) 2025-06-02 22:00:02.062592 :9F7B330B: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-02 22:00:02.062592 :9F7B330C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=167 time=1877360704 2025-06-02 22:00:02.062592 :9F7B330D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 22:00:02.062593 :9F7B330E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 22:00:02.062593 :9F7B330F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 22:00:02.062594 :9F7B3310:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 22:00:02.062594 :9F7B3311:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 22:00:02.063087 :9F7B3313: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-02 22:00:02.068069 :9F7B333A: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-02 22:00:02.068108 :9F7B333D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-06-02 22:00:02.071512 :9F7B3358: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-02 22:00:02.444928 :9F7B36BD: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-02 22:00:02.488695 :9F7B36C6: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-02 22:00:02.504265 :9F7B36CE: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-02 22:00:02.560950 :9F7B36DE: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-02 22:00:02.693152 :9F7B36EE: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-02 22:00:02.807616 :9F7B3701: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-02 22:00:02.817141 :9F7B3709: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-02 22:00:02.831778 :9F7B3711: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-02 22:00:02.846859 :9F7B3719: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-02 22:00:02.862007 :9F7B3721: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-02 22:00:02.871211 :9F7B3729: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-02 22:00:02.885922 :9F7B3731: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-02 22:00:02.895547 :9F7B3739: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-02 22:00:02.910756 :9F7B3741: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-02 22:00:02.920452 :9F7B3749: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-02 22:00:02.930039 :9F7B3751: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-02 22:00:02.944647 :9F7B3759: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-02 22:00:02.953522 :9F7B3761: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-02 22:00:02.968739 :9F7B3769: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-02 22:00:02.983873 :9F7B3771: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-02 22:00:02.998562 :9F7B3779: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-02 22:00:03.005885 :9F7B3781: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-02 22:00:03.020706 :9F7B3789: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-02 22:00:03.030151 :9F7B3791: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-02 22:00:03.045203 :9F7B3799: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-02 22:00:03.054374 :9F7B37A1: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-02 22:00:03.064416 :9F7B37D1: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-02 22:00:03.079336 :9F7B3816: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-02 22:00:03.088432 :9F7B381E: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-02 22:00:03.097306 :9F7B3826: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-02 22:00:03.112332 :9F7B382E: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-02 22:00:03.122260 :9F7B3836: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-02 22:00:03.137178 :9F7B383E: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-02 22:00:03.151931 :9F7B3846: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-02 22:00:03.161612 :9F7B384E: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-02 22:00:03.170798 :9F7B3856: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-02 22:00:03.186428*:9F7B3874:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB10450 estart=1748872803 eid=16784806 ctx=0x0000000787636618 cwh=0x00000000420A0428 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-02 22:00:03.186428*:9F7B3875:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB10450 curCtx=0x00000000420B2E80 pubTabIdxCnt=0 2025-06-02 22:00:03.186428*:9F7B3876:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB10450 2025-06-02 22:00:03.186428*:9F7B3877:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB10450 2025-06-02 22:00:03.186428*:9F7B3878:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000272EC288 estart=1748872801 eid=16777286 ctx=0x000000079F654AA8 cwh=0x00000000272E0158 cpu=1093750 buffg=79907 interb=19628032 r=920 rb=17326080 w=12 wb=2301952 2025-06-02 22:00:03.186428*:9F7B3879:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000272EC288 curCtx=0x00000000420B1E78 pubTabIdxCnt=0 2025-06-02 22:00:03.186428*:9F7B387A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000272EC288 2025-06-02 22:00:03.186428*:9F7B387B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000272EC288 2025-06-02 22:00:03.186428*:9F7B387C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB10450 curCtx=0x00000000420B2E80 xsc->flg4=65792 flags=2 2025-06-02 22:00:03.186428*:9F7B387D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB10450 2025-06-02 22:00:03.187156 :9F7B387E: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-02 22:00:03.201872 :9F7B3886: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-02 22:00:03.211047 :9F7B388E: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-02 22:00:03.219895 :9F7B3896: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-02 22:00:03.234495 :9F7B389E: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-02 22:00:03.244478 :9F7B38A6: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-02 22:00:03.253574 :9F7B38AE: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-02 22:00:03.268531 :9F7B38B6: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-02 22:00:03.277819 :9F7B38BE: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-02 22:00:03.285002 :9F7B38C6: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-02 22:00:03.292203 :9F7B38CE: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-02 22:00:03.299439 :9F7B38D6: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-02 22:00:03.306566 :9F7B38DE: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-02 22:00:03.313872 :9F7B38E6: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-02 22:00:03.321466 :9F7B38EE: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-02 22:00:03.328788 :9F7B38F6: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-02 22:00:03.335953 :9F7B38FE: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-02 22:00:03.343195 :9F7B3906: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-02 22:00:04.432564 :9F7B3B01: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-02 22:00:04.435375 :9F7B3B09: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-02 22:00:04.435661 :9F7B3B11: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-02 22:00:04.444487 :9F7B3B19: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-02 22:00:04.451961 :9F7B3B21: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-02 22:00:04.459396 :9F7B3B29: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-02 22:00:04.467836 :9F7B3B31: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-02 22:00:04.468127 :9F7B3B39: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-02 22:00:04.468127*:9F7B3B3C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000272EC288 curCtx=0x00000000420B1E78 xsc->flg4=65792 flags=2 2025-06-02 22:00:04.468127*:9F7B3B3D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000272EC288 2025-06-02 22:00:04.468386 :9F7B3B43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-06-02 22:00:04.472886 :9F7B3B44: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-02 22:00:04.472960 :9F7B3B47: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-02 22:00:04.473060 :9F7B3B54: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: 7776, J001)