Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250718220007\orcl_j001_19388_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:36187M/63366M, Ph+PgF:42084M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 19388, image: ORACLE.EXE (J001) *** 2025-07-18 22:00:07.581 *** SESSION ID:(209.51781) 2025-07-18 22:00:07.581 *** 2025-07-18 22:00:07.581 Process diagnostic dump for ORACLE.EXE (J001), OS id=19388, pid: 26, proc_ser: 28, sid: 209, sess_ser: 51781 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19388 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.072581 sec, exc=0.072581 sec, total=0.072581 sec wait times: max=0.500000 sec, heur=3.569215 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 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=5 seq_num=6 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510095 sec, exc=0.510095 sec, total=0.510095 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=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.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486491 sec, exc=0.486491 sec, total=0.486491 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 51781 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [4 samples, 22:00:04 - 22:00:07] 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-07-18 22:00:07.581 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 19388, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-18 21:57:24.883491 :B3D434F6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6992 pso_num=26 pso_serial#=13 2025-07-18 21:57:24.883637 :B3D434F7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=6992 (legacy spawn) 2025-07-18 21:57:24.890010 :B3D434F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:57:24.890195 :B3D43501: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-07-18 21:57:24.891003 :B3D43502:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:57:24.899682 :B3D43505:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:57:24.884 2025-07-18 21:57:24.899684 :B3D43506:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14972 pso_num=26 pso_serial#=14 2025-07-18 21:57:24.899825 :B3D43507:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14972 (legacy spawn) 2025-07-18 21:57:24.906262 :B3D43508:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:57:24.906423 :B3D4350F: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-07-18 21:57:24.907222 :B3D43512:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:57:28.007517 :B3D43608:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:57:27.994 2025-07-18 21:57:28.007518 :B3D43609:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=2696 pso_num=26 pso_serial#=15 2025-07-18 21:57:28.007661 :B3D4360A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=2696 (legacy spawn) 2025-07-18 21:57:28.014137 :B3D4360B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:57:28.014302 :B3D43610: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-07-18 21:57:28.015098 :B3D43615:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:57:28.023716 :B3D43618:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:57:28.009 2025-07-18 21:57:28.023718 :B3D43619:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17624 pso_num=26 pso_serial#=16 2025-07-18 21:57:28.023899 :B3D4361A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17624 (legacy spawn) 2025-07-18 21:57:28.030361 :B3D4361B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:57:28.030528 :B3D43620: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-07-18 21:57:28.031372 :B3D43625:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:57:29.565561 :B3D436A9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:57:29.556 2025-07-18 21:57:29.565563 :B3D436AA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8372 pso_num=26 pso_serial#=17 2025-07-18 21:57:29.565703 :B3D436AB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8372 (legacy spawn) 2025-07-18 21:57:29.572504 :B3D436AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:57:29.572677 :B3D436B4: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-07-18 21:57:29.573618 :B3D436B6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:58:26.934452 :B3D449C9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:58:26.926 2025-07-18 21:58:26.934454 :B3D449CA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16632 pso_num=26 pso_serial#=18 2025-07-18 21:58:26.934590 :B3D449CB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16632 (legacy spawn) 2025-07-18 21:58:26.941078 :B3D449D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:58:26.941246 :B3D449E1: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-07-18 21:58:26.942069 :B3D449E2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:58:31.774904 :B3D44B8F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:58:31.766 2025-07-18 21:58:31.774907 :B3D44B90:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18336 pso_num=26 pso_serial#=19 2025-07-18 21:58:31.775038 :B3D44B91:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18336 (legacy spawn) 2025-07-18 21:58:31.802134 :B3D44B92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:58:31.802324 :B3D44B97: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-07-18 21:58:31.803110 :B3D44B9C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:58:37.409046 :B3D44D25:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:58:37.395 2025-07-18 21:58:37.409048 :B3D44D26:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14888 pso_num=26 pso_serial#=20 2025-07-18 21:58:37.409185 :B3D44D27:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14888 (legacy spawn) 2025-07-18 21:58:37.415670 :B3D44D28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:58:37.415843 :B3D44D2D: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-07-18 21:58:37.416637 :B3D44D32:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:58:37.425326 :B3D44D35:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:58:37.410 2025-07-18 21:58:37.425328 :B3D44D36:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19380 pso_num=26 pso_serial#=21 2025-07-18 21:58:37.425470 :B3D44D37:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=19380 (legacy spawn) 2025-07-18 21:58:37.431916 :B3D44D43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:58:37.432084 :B3D44D4C: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-07-18 21:58:37.432901 :B3D44D4D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:59:13.884597 :B3D4599A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:59:13.870 2025-07-18 21:59:13.884600 :B3D4599B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19056 pso_num=26 pso_serial#=22 2025-07-18 21:59:13.884743 :B3D4599C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=19056 (legacy spawn) 2025-07-18 21:59:13.891328 :B3D4599D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:59:13.891494 :B3D459A2: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-07-18 21:59:13.892437 :B3D459A7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:59:13.901010 :B3D459AA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:59:13.886 2025-07-18 21:59:13.901012 :B3D459AB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18952 pso_num=26 pso_serial#=23 2025-07-18 21:59:13.901146 :B3D459AC:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18952 (legacy spawn) 2025-07-18 21:59:13.907527 :B3D459AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:59:13.907682 :B3D459B6: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-07-18 21:59:13.908484 :B3D459B7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:59:17.113692 :B3D45AC2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:59:17.099 2025-07-18 21:59:17.113694 :B3D45AC3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18772 pso_num=26 pso_serial#=24 2025-07-18 21:59:17.113837 :B3D45AC4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18772 (legacy spawn) 2025-07-18 21:59:17.121253 :B3D45AC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:59:17.121469 :B3D45ACA: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-07-18 21:59:17.122501 :B3D45ACF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:59:17.132041 :B3D45AD2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:59:17.130 2025-07-18 21:59:17.132043 :B3D45AD3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13400 pso_num=26 pso_serial#=25 2025-07-18 21:59:17.132193 :B3D45AD4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13400 (legacy spawn) 2025-07-18 21:59:17.139060 :B3D45AD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:59:17.139251 :B3D45ADD: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-07-18 21:59:17.140226 :B3D45ADF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:59:33.995678 :B3D45FF2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:59:33.987 2025-07-18 21:59:33.995680 :B3D45FF3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14056 pso_num=26 pso_serial#=26 2025-07-18 21:59:33.995843 :B3D45FF4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14056 (legacy spawn) 2025-07-18 21:59:34.002288 :B3D45FF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:59:34.002473 :B3D45FFA: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-07-18 21:59:34.003459 :B3D46013:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 21:59:35.338789 :B3D460B1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 21:59:35.331 2025-07-18 21:59:35.338790 :B3D460B2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17732 pso_num=26 pso_serial#=27 2025-07-18 21:59:35.338964 :B3D460B3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17732 (legacy spawn) 2025-07-18 21:59:35.345430 :B3D460B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 21:59:35.345608 :B3D460BD: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-07-18 21:59:35.346436 :B3D460BE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-18 22:00:00.546266 :B3D46A6A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-18 22:00:00.535 2025-07-18 22:00:00.546268 :B3D46A6B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19388 pso_num=26 pso_serial#=28 2025-07-18 22:00:00.546402 :B3D46A6C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19388 osp_idx=24 osp_ver=646708 osp_pg=0 (spawn #399159) 2025-07-18 22:00:00.546403 :B3D46A6D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 22:00:00.546403 :B3D46A6E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=196 time=1556776439 2025-07-18 22:00:00.546404 :B3D46A6F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 22:00:00.546404 :B3D46A70:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 22:00:00.546405 :B3D46A71:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 22:00:00.546405 :B3D46A72:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 22:00:00.546405 :B3D46A73:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 22:00:00.546912 :B3D46A74: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-07-18 22:00:00.552096 :B3D46A9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:00.552136 :B3D46A9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-18 22:00:00.555502 :B3D46ABA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:00.938637 :B3D46E08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:00.982147 :B3D46E10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:00.997793 :B3D46E18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.055189 :B3D46E50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.187422 :B3D46E60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.301243 :B3D46E74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.311297 :B3D46E7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.326699 :B3D46E84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.341866 :B3D46E8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.357473 :B3D46E94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.367222 :B3D46E9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.383133 :B3D46EA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.393443 :B3D46EAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.409794 :B3D46EB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.420202 :B3D46EBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.430404 :B3D46EC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.446190 :B3D46ECC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.456313 :B3D46ED4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.472466 :B3D46EDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.488610 :B3D46EE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.504123 :B3D46EEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.519852 :B3D46EF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.529625 :B3D46EFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.544771 :B3D46F04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.550564*:B3D46F0C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000004F270388 estart=1752847200 eid=16801755 ctx=0x0000000783637648 cwh=0x0000000029A40068 cpu=15625 buffg=179 interb=0 r=0 rb=0 w=0 wb=0 2025-07-18 22:00:01.550564*:B3D46F0D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000004F270388 curCtx=0x000000004F83AB78 pubTabIdxCnt=0 2025-07-18 22:00:01.550564*:B3D46F0E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000004F270388 2025-07-18 22:00:01.550564*:B3D46F0F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000004F270388 2025-07-18 22:00:01.550564*:B3D46F10:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC288 estart=1752847200 eid=16777332 ctx=0x000000079F654AA8 cwh=0x0000000028BB0158 cpu=968750 buffg=71868 interb=13877248 r=570 rb=11583488 w=12 wb=2293760 2025-07-18 22:00:01.550564*:B3D46F11:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC288 curCtx=0x000000004F3B5FA0 pubTabIdxCnt=0 2025-07-18 22:00:01.550564*:B3D46F12:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC288 2025-07-18 22:00:01.550564*:B3D46F13:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC288 2025-07-18 22:00:01.554205 :B3D46F14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.554205*:B3D46F1C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000004F270388 curCtx=0x000000004F83AB78 xsc->flg4=65792 flags=0 2025-07-18 22:00:01.554205*:B3D46F1D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000004F270388 2025-07-18 22:00:01.564335 :B3D46F1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.573473 :B3D46F26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.589132 :B3D46F2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.598379 :B3D46F36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.613684 :B3D46F49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.620909 :B3D46F51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.636779 :B3D46F59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.651948 :B3D46F61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.661876 :B3D46F69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.670915 :B3D46F71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.686024 :B3D46F79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.701666 :B3D46F81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.717265 :B3D46F89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.727741 :B3D46F91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.738385 :B3D46F99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.748356 :B3D46FA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.758226 :B3D46FA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.770413 :B3D46FB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.780332 :B3D46FB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.795954 :B3D46FC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.805721 :B3D46FC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.813057 :B3D46FD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.820502 :B3D46FD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.827984 :B3D46FE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.835424 :B3D46FE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.842951 :B3D46FF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.850645 :B3D46FF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:01.858238 :B3D47001:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:02.991030 :B3D471F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:02.991382 :B3D471FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:02.991382*:B3D471FF:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC288 curCtx=0x000000004F3B5FA0 xsc->flg4=65792 flags=2 2025-07-18 22:00:02.991382*:B3D47200:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC288 2025-07-18 22:00:02.991634 :B3D47206:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-18 22:00:02.996146 :B3D47207:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:02.996237 :B3D4720A: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-07-18 22:00:02.996335 :B3D47217: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-07-18 22:00:04.008664 :B3D47259:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-18 22:00:04.010793 :B3D4725A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.010838 :B3D4725D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-18 22:00:04.017435 :B3D47263:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.018154 :B3D4726B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.018858 :B3D47273:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.019051 :B3D4727B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-18 22:00:04.020570 :B3D4727C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.020617 :B3D4727F: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-07-18 22:00:04.020708 :B3D4728C: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: 19388, J001)