Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250820220008\orcl_j001_21864_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:35348M/63366M, Ph+PgF:40577M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 21864, image: ORACLE.EXE (J001) *** 2025-08-20 22:00:09.004 *** SESSION ID:(219.40363) 2025-08-20 22:00:09.004 *** 2025-08-20 22:00:09.004 Process diagnostic dump for ORACLE.EXE (J001), OS id=21864, pid: 27, proc_ser: 168, sid: 219, sess_ser: 40363 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21864 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.138550 sec, exc=0.138550 sec, total=0.138550 sec wait times: max=0.500000 sec, heur=6.046201 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=12 seq_num=13 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=11 seq_num=12 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 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=10 seq_num=11 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500132 sec, exc=0.500132 sec, total=0.500132 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499023 sec, exc=0.499023 sec, total=0.499023 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.375968 sec, exc=0.375968 sec, total=0.375968 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499403 sec, exc=0.499403 sec, total=0.499403 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time Sampled Session History of session 219 serial 40363 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:02 - 22:00:09] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-20 22:00:09.004 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 21864, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-20 21:57:34.394897 :C26C8E7A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:57:34.412998 :C26C8E7D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:57:34.404 2025-08-20 21:57:34.413000 :C26C8E7E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21908 pso_num=27 pso_serial#=153 2025-08-20 21:57:34.413138 :C26C8E7F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21908 (legacy spawn) 2025-08-20 21:57:34.419744 :C26C8E94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:34.419902 :C26C8E9D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:57:34.420737 :C26C8EB2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:58:10.690617 :C26C9A57:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:58:10.687 2025-08-20 21:58:10.690619 :C26C9A58:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21872 pso_num=27 pso_serial#=154 2025-08-20 21:58:10.690753 :C26C9A59:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21872 (legacy spawn) 2025-08-20 21:58:10.697176 :C26C9A5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:10.697368 :C26C9A5F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:58:10.719112 :C26C9A63:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:58:10.728268 :C26C9A66:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:58:10.718 2025-08-20 21:58:10.728270 :C26C9A67:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14644 pso_num=27 pso_serial#=155 2025-08-20 21:58:10.728423 :C26C9A68:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14644 (legacy spawn) 2025-08-20 21:58:10.735755 :C26C9A69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:10.735933 :C26C9A71:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:58:10.736927 :C26C9A73:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:58:13.970561 :C26C9B63:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:58:13.957 2025-08-20 21:58:13.970564 :C26C9B64:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19156 pso_num=27 pso_serial#=156 2025-08-20 21:58:13.970709 :C26C9B65:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19156 (legacy spawn) 2025-08-20 21:58:13.977233 :C26C9B66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:13.977417 :C26C9B6B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:58:13.978233 :C26C9B70:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:58:13.986953 :C26C9B73:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:58:13.973 2025-08-20 21:58:13.986955 :C26C9B74:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20828 pso_num=27 pso_serial#=157 2025-08-20 21:58:13.987094 :C26C9B75:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20828 (legacy spawn) 2025-08-20 21:58:14.005949 :C26C9B76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:14.006118 :C26C9B7F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:58:14.007017 :C26C9B80:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:58:16.527765 :C26C9C74:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:58:16.519 2025-08-20 21:58:16.527767 :C26C9C75:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17716 pso_num=27 pso_serial#=158 2025-08-20 21:58:16.527909 :C26C9C76:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17716 (legacy spawn) 2025-08-20 21:58:16.534413 :C26C9C77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:16.534580 :C26C9C7C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:58:16.535434 :C26C9C81:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:58:22.623900 :C26C9E49:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:58:22.616 2025-08-20 21:58:22.623902 :C26C9E4A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20532 pso_num=27 pso_serial#=159 2025-08-20 21:58:22.624037 :C26C9E4B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20532 (legacy spawn) 2025-08-20 21:58:22.630617 :C26C9E4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:22.651004 :C26C9E69:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:58:22.651950 :C26C9E6A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:24.168207 :C26CB1B7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:24.168 2025-08-20 21:59:24.168209 :C26CB1B8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20192 pso_num=27 pso_serial#=160 2025-08-20 21:59:24.168350 :C26CB1B9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20192 (legacy spawn) 2025-08-20 21:59:24.175036 :C26CB1BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:24.175230 :C26CB1BF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:24.176085 :C26CB1C4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:24.185407 :C26CB1C7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:24.184 2025-08-20 21:59:24.185409 :C26CB1C8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21560 pso_num=27 pso_serial#=161 2025-08-20 21:59:24.185558 :C26CB1C9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21560 (legacy spawn) 2025-08-20 21:59:24.192202 :C26CB1CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:24.192377 :C26CB1D3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:24.193240 :C26CB1D4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:24.724254 :C26CB235:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:24.715 2025-08-20 21:59:24.724256 :C26CB236:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20032 pso_num=27 pso_serial#=162 2025-08-20 21:59:24.724392 :C26CB237:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20032 (legacy spawn) 2025-08-20 21:59:24.730824 :C26CB238:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:24.730991 :C26CB241:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:24.731790 :C26CB242:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:25.160695 :C26CB27D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:25.152 2025-08-20 21:59:25.160696 :C26CB27E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16340 pso_num=27 pso_serial#=163 2025-08-20 21:59:25.160838 :C26CB27F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16340 (legacy spawn) 2025-08-20 21:59:25.167561 :C26CB280:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:25.167732 :C26CB285:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:25.168605 :C26CB28A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:27.394813 :C26CB2E7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:27.387 2025-08-20 21:59:27.394815 :C26CB2E8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13480 pso_num=27 pso_serial#=164 2025-08-20 21:59:27.394961 :C26CB2E9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13480 (legacy spawn) 2025-08-20 21:59:27.401408 :C26CB2EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:27.401576 :C26CB2EF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:27.402395 :C26CB2F4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:27.411143 :C26CB2F7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:27.402 2025-08-20 21:59:27.411144 :C26CB2F8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3560 pso_num=27 pso_serial#=165 2025-08-20 21:59:27.411279 :C26CB2F9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3560 (legacy spawn) 2025-08-20 21:59:27.417811 :C26CB2FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:27.417975 :C26CB303:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:27.418865 :C26CB304:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:30.084515 :C26CB3DD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:30.071 2025-08-20 21:59:30.084517 :C26CB3DE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=22020 pso_num=27 pso_serial#=166 2025-08-20 21:59:30.084658 :C26CB3DF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=22020 (legacy spawn) 2025-08-20 21:59:30.091165 :C26CB3E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:30.091339 :C26CB3E5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:30.092134 :C26CB3EA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 21:59:30.100917 :C26CB3ED:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 21:59:30.087 2025-08-20 21:59:30.100920 :C26CB3EE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20152 pso_num=27 pso_serial#=167 2025-08-20 21:59:30.101056 :C26CB3EF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20152 (legacy spawn) 2025-08-20 21:59:30.107565 :C26CB3F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:30.107740 :C26CB3F9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 21:59:30.108609 :C26CB3FA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-20 22:00:00.376017 :C26CBEEA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-20 22:00:00.364 2025-08-20 22:00:00.376019 :C26CBEEB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21864 pso_num=27 pso_serial#=168 2025-08-20 22:00:00.376154 :C26CBEEC:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=21864 osp_idx=25 osp_ver=314846 osp_pg=0 (spawn #509155) 2025-08-20 22:00:00.376155 :C26CBEED:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 22:00:00.376155 :C26CBEEE:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=165 time=112902377 2025-08-20 22:00:00.376156 :C26CBEEF:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 22:00:00.376156 :C26CBEF0:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 22:00:00.376157 :C26CBEF1:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 22:00:00.376157 :C26CBEF2:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 22:00:00.376157 :C26CBEF3:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 22:00:00.376649 :C26CBEF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.381692 :C26CBF13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.381727 :C26CBF16:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-08-20 22:00:00.385109 :C26CBF39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.773798 :C26CC2A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.817618 :C26CC2B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.833536 :C26CC2B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.891057 :C26CC2D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.025297 :C26CC2E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.140867 :C26CC2EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.151128 :C26CC2F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.167547 :C26CC2FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.184199 :C26CC304:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.201790 :C26CC30C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.212581 :C26CC314:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.224048 :C26CC327:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.241112 :C26CC352:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.257721 :C26CC35A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.268108 :C26CC362:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.278296 :C26CC36A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.287949 :C26CC372:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.303691 :C26CC37A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.320019 :C26CC382:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.336344 :C26CC38A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.352345 :C26CC392:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.368219 :C26CC39A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.377962 :C26CC3A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.393772 :C26CC3AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.402990 :C26CC3B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.413025 :C26CC3BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.429263 :C26CC3C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.438868 :C26CC3CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.454645 :C26CC3D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.463228 :C26CC3DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.470433 :C26CC3E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.480946 :C26CC3F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.490520 :C26CC3FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.506886 :C26CC406:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.523051 :C26CC40E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.531090 :C26CC416:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.541192 :C26CC41E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.557331 :C26CC426:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.574583 :C26CC42E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.587431 :C26CC436:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.603508 :C26CC43E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.614134 :C26CC446:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.624100 :C26CC44E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.634047 :C26CC456:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.643677 :C26CC45E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.660066 :C26CC466:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.670218 :C26CC46E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.677782 :C26CC476:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.685254 :C26CC47E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.692798 :C26CC486:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.700524 :C26CC48E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.708282 :C26CC496:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.715923 :C26CC49E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.723697 :C26CC4A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.731602 :C26CC4AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.739300 :C26CC4B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.746821 :C26CC4BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.754530 :C26CC4C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:01.762141 :C26CC4CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:02.003550*:C26CC4FE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B516918 estart=1755698401 eid=16777515 ctx=0x00000007831EA738 cwh=0x000000002B540248 cpu=234375 buffg=45017 interb=229376 r=14 rb=229376 w=0 wb=0 2025-08-20 22:00:02.003550*:C26CC4FF:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B516918 curCtx=0x000000002B518338 pubTabIdxCnt=0 2025-08-20 22:00:02.003550*:C26CC500:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B516918 2025-08-20 22:00:02.003550*:C26CC501:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B516918 2025-08-20 22:00:02.003550*:C26CC502:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028FFC288 estart=1755698400 eid=16777365 ctx=0x000000079F654AA8 cwh=0x0000000028FF0158 cpu=1578125 buffg=133889 interb=30441472 r=1578 rb=28123136 w=12 wb=2318336 2025-08-20 22:00:02.003550*:C26CC503:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028FFC288 curCtx=0x000000002B5534D8 pubTabIdxCnt=0 2025-08-20 22:00:02.003550*:C26CC504:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028FFC288 2025-08-20 22:00:02.003550*:C26CC505:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028FFC288 2025-08-20 22:00:02.300426*:C26CC662:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B516918 curCtx=0x000000002B518338 xsc->flg4=65792 flags=1 2025-08-20 22:00:02.300426*:C26CC663:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B516918 2025-08-20 22:00:02.958061 :C26CC6BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:02.958382 :C26CC6C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:02.958382*:C26CC6C9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028FFC288 curCtx=0x000000002B5534D8 xsc->flg4=65792 flags=2 2025-08-20 22:00:02.958382*:C26CC6CB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028FFC288 2025-08-20 22:00:02.958634 :C26CC6D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-08-20 22:00:02.963060 :C26CC6D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 22:00:02.963141 :C26CC6D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-20 22:00:02.963226 :C26CC6E1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-20 22:00:04.004446 :C26CC78D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 22:00:05.379511 :C26CC8CC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 21864, J001)