Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831060007\orcl_j001_16692_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:35059M/63366M, Ph+PgF:40004M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 16692, image: ORACLE.EXE (J001) *** 2025-08-31 06:00:07.404 *** SESSION ID:(209.25483) 2025-08-31 06:00:07.404 *** 2025-08-31 06:00:07.404 Process diagnostic dump for ORACLE.EXE (J001), OS id=16692, pid: 26, proc_ser: 235, sid: 209, sess_ser: 25483 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16692 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.071755 sec, exc=0.071755 sec, total=0.071755 sec wait times: max=0.500000 sec, heur=4.579300 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 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=5 seq_num=6 snap_id=1 wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.497382 sec, exc=0.497382 sec, total=0.497382 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 25483 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 06:00:02 - 06:00:07] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 06:00:07.404 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 16692, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 05:56:28.179225 :C6F7A1B5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:56:38.164618 :C6F7A486:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:56:38.156 2025-08-31 05:56:38.164619 :C6F7A487:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20448 pso_num=26 pso_serial#=219 2025-08-31 05:56:38.164766 :C6F7A488:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=20448 (legacy spawn) 2025-08-31 05:56:38.171497 :C6F7A489: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-08-31 05:56:38.171674 :C6F7A48E: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-08-31 05:56:38.172527 :C6F7A493:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:57:24.854515 :C6F7B3C3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:57:24.841 2025-08-31 05:57:24.854518 :C6F7B3C4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13480 pso_num=26 pso_serial#=220 2025-08-31 05:57:24.854671 :C6F7B3C5:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13480 (legacy spawn) 2025-08-31 05:57:24.861722 :C6F7B3C6: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-08-31 05:57:24.861950 :C6F7B3CB: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-08-31 05:57:24.862827 :C6F7B3D0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:57:26.682136 :C6F7B44B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:57:26.669 2025-08-31 05:57:26.682138 :C6F7B44C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6020 pso_num=26 pso_serial#=221 2025-08-31 05:57:26.682270 :C6F7B44D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=6020 (legacy spawn) 2025-08-31 05:57:26.688666 :C6F7B44E: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-08-31 05:57:26.688831 :C6F7B457: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-08-31 05:57:26.689615 :C6F7B458:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:57:26.698333 :C6F7B45B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:57:26.685 2025-08-31 05:57:26.698335 :C6F7B45C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7572 pso_num=26 pso_serial#=222 2025-08-31 05:57:26.698472 :C6F7B45D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=7572 (legacy spawn) 2025-08-31 05:57:26.717354 :C6F7B486: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-08-31 05:57:26.717387 :C6F7B489: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-08-31 05:57:26.717531 :C6F7B490: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-08-31 05:57:26.718402 :C6F7B49D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:57:29.959463 :C6F7B58C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:57:29.950 2025-08-31 05:57:29.959465 :C6F7B58D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13240 pso_num=26 pso_serial#=223 2025-08-31 05:57:29.959604 :C6F7B58E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13240 (legacy spawn) 2025-08-31 05:57:29.966632 :C6F7B58F: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-08-31 05:57:29.966806 :C6F7B598: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-08-31 05:57:29.967722 :C6F7B599:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:57:37.989106 :C6F7B7E2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:57:37.974 2025-08-31 05:57:37.989108 :C6F7B7E3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22656 pso_num=26 pso_serial#=224 2025-08-31 05:57:37.989255 :C6F7B7E4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22656 (legacy spawn) 2025-08-31 05:57:37.996036 :C6F7B7E5: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-08-31 05:57:37.996211 :C6F7B7EA: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-08-31 05:57:37.997081 :C6F7B7EF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:57:38.006262 :C6F7B7F2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:57:38.005 2025-08-31 05:57:38.006264 :C6F7B7F3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22192 pso_num=26 pso_serial#=225 2025-08-31 05:57:38.006401 :C6F7B7F4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22192 (legacy spawn) 2025-08-31 05:57:38.013160 :C6F7B7F5: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-08-31 05:57:38.013334 :C6F7B7FE: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-08-31 05:57:38.014228 :C6F7B7FF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:58:11.412392 :C6F7C39C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:58:11.399 2025-08-31 05:58:11.412394 :C6F7C39D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22048 pso_num=26 pso_serial#=226 2025-08-31 05:58:11.412538 :C6F7C39E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22048 (legacy spawn) 2025-08-31 05:58:11.419188 :C6F7C39F: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-08-31 05:58:11.419361 :C6F7C3A4: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-08-31 05:58:11.420187 :C6F7C3A9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:58:17.416589 :C6F7C5BE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:58:17.403 2025-08-31 05:58:17.416592 :C6F7C5BF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21904 pso_num=26 pso_serial#=227 2025-08-31 05:58:17.416728 :C6F7C5C0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=21904 (legacy spawn) 2025-08-31 05:58:17.423217 :C6F7C5C1: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-08-31 05:58:17.423397 :C6F7C5C6: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-08-31 05:58:17.424184 :C6F7C5CB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:58:17.432984 :C6F7C5CE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:58:17.419 2025-08-31 05:58:17.432986 :C6F7C5CF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22144 pso_num=26 pso_serial#=228 2025-08-31 05:58:17.433126 :C6F7C5D0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22144 (legacy spawn) 2025-08-31 05:58:17.439708 :C6F7C5D1: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-08-31 05:58:17.439881 :C6F7C5DA: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-08-31 05:58:17.440705 :C6F7C5DB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:58:33.042818 :C6F7CB00:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:58:33.034 2025-08-31 05:58:33.042820 :C6F7CB01:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11644 pso_num=26 pso_serial#=229 2025-08-31 05:58:33.042963 :C6F7CB02:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11644 (legacy spawn) 2025-08-31 05:58:33.049472 :C6F7CB03: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-08-31 05:58:33.049641 :C6F7CB0C: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-08-31 05:58:33.050448 :C6F7CB0D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:59:13.608890 :C6F7D817:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:59:13.596 2025-08-31 05:59:13.608892 :C6F7D818:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22116 pso_num=26 pso_serial#=230 2025-08-31 05:59:13.609031 :C6F7D819:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22116 (legacy spawn) 2025-08-31 05:59:13.615562 :C6F7D81A: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-08-31 05:59:13.615742 :C6F7D81F: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-08-31 05:59:13.616523 :C6F7D824:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:59:26.692134 :C6F7DBFA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:59:26.692 2025-08-31 05:59:26.692136 :C6F7DBFB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20572 pso_num=26 pso_serial#=231 2025-08-31 05:59:26.692273 :C6F7DBFC:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=20572 (legacy spawn) 2025-08-31 05:59:26.699225 :C6F7DBFD: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-08-31 05:59:26.699443 :C6F7DC05: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-08-31 05:59:26.700372 :C6F7DC07:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:59:26.709836 :C6F7DC0A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:59:26.708 2025-08-31 05:59:26.709838 :C6F7DC0B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20512 pso_num=26 pso_serial#=232 2025-08-31 05:59:26.709979 :C6F7DC0C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=20512 (legacy spawn) 2025-08-31 05:59:26.716825 :C6F7DC0D: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-08-31 05:59:26.717001 :C6F7DC16: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-08-31 05:59:26.717916 :C6F7DC17:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:59:29.886465 :C6F7DD45:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:59:29.874 2025-08-31 05:59:29.886467 :C6F7DD46:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22508 pso_num=26 pso_serial#=233 2025-08-31 05:59:29.886610 :C6F7DD47:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22508 (legacy spawn) 2025-08-31 05:59:29.893080 :C6F7DD48: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-08-31 05:59:29.893261 :C6F7DD51: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-08-31 05:59:29.894058 :C6F7DD52:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 05:59:29.902794 :C6F7DD55:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 05:59:29.889 2025-08-31 05:59:29.902796 :C6F7DD56:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22520 pso_num=26 pso_serial#=234 2025-08-31 05:59:29.902930 :C6F7DD57:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22520 (legacy spawn) 2025-08-31 05:59:29.909369 :C6F7DD58: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-08-31 05:59:29.909538 :C6F7DD61: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-08-31 05:59:29.910380 :C6F7DD62:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 06:00:00.332445 :C6F7E87A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 06:00:00.322 2025-08-31 06:00:00.332447 :C6F7E87B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16692 pso_num=26 pso_serial#=235 2025-08-31 06:00:00.332583 :C6F7E87C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16692 osp_idx=24 osp_ver=764470 osp_pg=0 (spawn #543601) 2025-08-31 06:00:00.332584 :C6F7E87D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 06:00:00.332584 :C6F7E87E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=174 time=1005651627 2025-08-31 06:00:00.332585 :C6F7E87F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 06:00:00.332585 :C6F7E880:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 06:00:00.332586 :C6F7E881:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 06:00:00.332586 :C6F7E882:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 06:00:00.332586 :C6F7E883:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 06:00:00.333087 :C6F7E884: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-08-31 06:00:00.338043 :C6F7E8A4: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-08-31 06:00:00.338081 :C6F7E8A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-08-31 06:00:00.341553 :C6F7E8CA: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-08-31 06:00:00.721107 :C6F7EC42: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-08-31 06:00:00.768117 :C6F7EC52: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-08-31 06:00:00.785329 :C6F7EC5A: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-08-31 06:00:00.843582 :C6F7EC62: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-08-31 06:00:00.977564 :C6F7EC8D: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-08-31 06:00:01.092170 :C6F7EC95: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-08-31 06:00:01.102199 :C6F7EC9D: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-08-31 06:00:01.118117 :C6F7ECA5: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-08-31 06:00:01.133807 :C6F7ECAD: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-08-31 06:00:01.149929 :C6F7ECB5: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-08-31 06:00:01.159998 :C6F7ECBD: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-08-31 06:00:01.175469 :C6F7ECC5: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-08-31 06:00:01.185087 :C6F7ECD9: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-08-31 06:00:01.200790 :C6F7ECE1: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-08-31 06:00:01.211169 :C6F7ECE9: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-08-31 06:00:01.221203 :C6F7ECF1: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-08-31 06:00:01.236913 :C6F7ECF9: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-08-31 06:00:01.246630 :C6F7ED01: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-08-31 06:00:01.263002 :C6F7ED09: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-08-31 06:00:01.279217 :C6F7ED11: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-08-31 06:00:01.295111 :C6F7ED19: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-08-31 06:00:01.302670 :C6F7ED21: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-08-31 06:00:01.318412 :C6F7ED29: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-08-31 06:00:01.328603 :C6F7ED31: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-08-31 06:00:01.338893 :C6F7ED39: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-08-31 06:00:01.354592 :C6F7ED41: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-08-31 06:00:01.364984 :C6F7ED49: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-08-31 06:00:01.374648 :C6F7ED51: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-08-31 06:00:01.390552 :C6F7ED59: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-08-31 06:00:01.399805 :C6F7ED61: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-08-31 06:00:01.415376 :C6F7ED69: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-08-31 06:00:01.425596 :C6F7ED71: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-08-31 06:00:01.441528 :C6F7ED79: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-08-31 06:00:01.457291 :C6F7ED81: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-08-31 06:00:01.467405 :C6F7ED89: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-08-31 06:00:01.476597 :C6F7ED91: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-08-31 06:00:01.492408 :C6F7ED99: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-08-31 06:00:01.507931 :C6F7EDA1: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-08-31 06:00:01.523426 :C6F7EDA9: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-08-31 06:00:01.533152 :C6F7EDB1: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-08-31 06:00:01.543039 :C6F7EDB9: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-08-31 06:00:01.553438 :C6F7EDC1: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-08-31 06:00:01.565545 :C6F7EDC9: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-08-31 06:00:01.575621 :C6F7EDD1: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-08-31 06:00:01.591577 :C6F7EDD9: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-08-31 06:00:01.601160 :C6F7EDE1: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-08-31 06:00:01.608558 :C6F7EDE9: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-08-31 06:00:01.615976 :C6F7EDF1: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-08-31 06:00:01.623538 :C6F7EDF9: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-08-31 06:00:01.631001 :C6F7EE01: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-08-31 06:00:01.638624 :C6F7EE09: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-08-31 06:00:01.646132 :C6F7EE11: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-08-31 06:00:01.653471 :C6F7EE19: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-08-31 06:00:01.660925 :C6F7EE21: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-08-31 06:00:01.712542*:C6F7EE29:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299C6918 estart=1756591200 eid=16777537 ctx=0x00000007831EA738 cwh=0x00000000299F0248 cpu=46875 buffg=12555 interb=114688 r=7 rb=114688 w=0 wb=0 2025-08-31 06:00:01.712542*:C6F7EE2C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299C6918 curCtx=0x000000005C771CD0 pubTabIdxCnt=0 2025-08-31 06:00:01.712542*:C6F7EE2E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299C6918 2025-08-31 06:00:01.712542*:C6F7EE2F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299C6918 2025-08-31 06:00:01.712542*:C6F7EE31:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC288 estart=1756591199 eid=16777376 ctx=0x000000079F654AA8 cwh=0x0000000028BB0158 cpu=1296875 buffg=100061 interb=27951104 r=1431 rb=25673728 w=12 wb=2277376 2025-08-31 06:00:01.712542*:C6F7EE35:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC288 curCtx=0x000000005C794F98 pubTabIdxCnt=0 2025-08-31 06:00:01.712542*:C6F7EE36:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC288 2025-08-31 06:00:01.712542*:C6F7EE37:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC288 2025-08-31 06:00:02.212542*:C6F7EFB5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299C6918 curCtx=0x000000005C771CD0 xsc->flg4=65792 flags=1 2025-08-31 06:00:02.212542*:C6F7EFB6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299C6918 2025-08-31 06:00:02.820763 :C6F7EFD2: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-08-31 06:00:02.821118 :C6F7EFDA: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-08-31 06:00:02.821118*:C6F7EFDD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC288 curCtx=0x000000005C794F98 xsc->flg4=65792 flags=2 2025-08-31 06:00:02.821118*:C6F7EFDE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC288 2025-08-31 06:00:02.821370 :C6F7EFE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-08-31 06:00:02.825816 :C6F7EFE5: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-08-31 06:00:02.825894 :C6F7EFE8: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-08-31 06:00:02.825986 :C6F7EFF5: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: 16692, J001)