Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250727060009\orcl_j001_19440_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:35977M/63366M, Ph+PgF:41712M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 19440, image: ORACLE.EXE (J001) *** 2025-07-27 06:00:09.354 *** SESSION ID:(209.8429) 2025-07-27 06:00:09.354 *** 2025-07-27 06:00:09.354 Process diagnostic dump for ORACLE.EXE (J001), OS id=19440, pid: 26, proc_ser: 223, sid: 209, sess_ser: 8429 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19440 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.308505 sec, exc=0.308505 sec, total=0.308505 sec wait times: max=0.500000 sec, heur=4.814253 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.000008 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.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.000009 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.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.000013 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.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.000008 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.510116 sec, exc=0.510116 sec, total=0.510116 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=4 seq_num=5 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 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.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.000014 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.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.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.000010 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.495569 sec, exc=0.495569 sec, total=0.495569 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 8429 --------------------------------------------------- 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:04 - 06:00:09] idle wait at each sample [session created at: 06:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-27 06:00:09.354 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 19440, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-27 05:57:25.647546 :B7891F72:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:57:37.832823 :B789235A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:57:37.818 2025-07-27 05:57:37.832825 :B789235B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16116 pso_num=26 pso_serial#=207 2025-07-27 05:57:37.832974 :B789235C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16116 (legacy spawn) 2025-07-27 05:57:37.839812 :B789235D:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:57:37.839997 :B7892362: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-27 05:57:37.840882 :B7892367:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:57:42.187103 :B78924BE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:57:42.172 2025-07-27 05:57:42.187105 :B78924BF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13660 pso_num=26 pso_serial#=208 2025-07-27 05:57:42.187263 :B78924C0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13660 (legacy spawn) 2025-07-27 05:57:42.193790 :B78924C1:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:57:42.193973 :B78924CA: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-27 05:57:42.194815 :B78924CB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:57:42.203509 :B78924CE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:57:42.204 2025-07-27 05:57:42.203511 :B78924CF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11764 pso_num=26 pso_serial#=209 2025-07-27 05:57:42.203654 :B78924D0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11764 (legacy spawn) 2025-07-27 05:57:42.210543 :B78924D1:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:57:42.210700 :B78924DA: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-27 05:57:42.211760 :B78924DB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:57:45.284500 :B78925C9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:57:45.271 2025-07-27 05:57:45.284502 :B78925CA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14676 pso_num=26 pso_serial#=210 2025-07-27 05:57:45.284644 :B78925CB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14676 (legacy spawn) 2025-07-27 05:57:45.291206 :B78925CC:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:57:45.291390 :B78925D4: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-27 05:57:45.292205 :B78925D6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:57:45.300935 :B78925D9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:57:45.286 2025-07-27 05:57:45.300937 :B78925DA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14052 pso_num=26 pso_serial#=211 2025-07-27 05:57:45.301071 :B78925DB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14052 (legacy spawn) 2025-07-27 05:57:45.307592 :B78925DC:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:57:45.307756 :B78925E5: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-27 05:57:45.308576 :B78925E6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:58:21.497648 :B7893260:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:58:21.483 2025-07-27 05:58:21.497650 :B7893261:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19140 pso_num=26 pso_serial#=212 2025-07-27 05:58:21.497796 :B7893262:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=19140 (legacy spawn) 2025-07-27 05:58:21.504566 :B7893263:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:58:21.504749 :B789326C: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-27 05:58:21.505588 :B789326D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:58:21.514531 :B7893270:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:58:21.499 2025-07-27 05:58:21.514533 :B7893271:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18352 pso_num=26 pso_serial#=213 2025-07-27 05:58:21.514688 :B7893272:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18352 (legacy spawn) 2025-07-27 05:58:21.521429 :B7893273:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:58:21.521633 :B789327C: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-27 05:58:21.522515 :B789327D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:58:33.454571 :B789362D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:58:33.454 2025-07-27 05:58:33.454573 :B789362E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9604 pso_num=26 pso_serial#=214 2025-07-27 05:58:33.454706 :B789362F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9604 (legacy spawn) 2025-07-27 05:58:33.461158 :B7893630:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:58:33.461331 :B7893639: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-27 05:58:33.462138 :B789363A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:58:40.017470 :B789381D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:58:40.009 2025-07-27 05:58:40.017472 :B789381E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13284 pso_num=26 pso_serial#=215 2025-07-27 05:58:40.017613 :B789381F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13284 (legacy spawn) 2025-07-27 05:58:40.024099 :B7893820:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:58:40.024279 :B7893825: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-27 05:58:40.025081 :B789382A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:31.107458 :B7894867:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:31.105 2025-07-27 05:59:31.107460 :B7894868:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18968 pso_num=26 pso_serial#=216 2025-07-27 05:59:31.107612 :B7894869:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18968 (legacy spawn) 2025-07-27 05:59:31.114637 :B789486A:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:31.114831 :B7894873: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-27 05:59:31.115770 :B7894874:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:31.125425 :B7894877:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:31.121 2025-07-27 05:59:31.125428 :B7894878:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19128 pso_num=26 pso_serial#=217 2025-07-27 05:59:31.125585 :B7894879:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=19128 (legacy spawn) 2025-07-27 05:59:31.132559 :B789487A:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:31.132723 :B7894883: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-27 05:59:31.133640 :B7894884:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:34.295614 :B789498E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:34.282 2025-07-27 05:59:34.295616 :B789498F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18716 pso_num=26 pso_serial#=218 2025-07-27 05:59:34.295760 :B7894990:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18716 (legacy spawn) 2025-07-27 05:59:34.302369 :B78949D6:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:34.302541 :B78949DC: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-27 05:59:34.303380 :B78949E0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:34.312065 :B78949E3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:34.297 2025-07-27 05:59:34.312067 :B78949E4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18600 pso_num=26 pso_serial#=219 2025-07-27 05:59:34.312203 :B78949E5:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18600 (legacy spawn) 2025-07-27 05:59:34.318691 :B78949E6:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:34.318846 :B78949EF: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-27 05:59:34.319707 :B78949F0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:41.649903 :B7894C2B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:41.640 2025-07-27 05:59:41.649905 :B7894C2C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3560 pso_num=26 pso_serial#=220 2025-07-27 05:59:41.650043 :B7894C2D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=3560 (legacy spawn) 2025-07-27 05:59:41.656646 :B7894C2E:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:41.656816 :B7894C33: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-27 05:59:41.657643 :B7894C38:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:42.180291 :B7894C3B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:42.172 2025-07-27 05:59:42.180293 :B7894C3C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1876 pso_num=26 pso_serial#=221 2025-07-27 05:59:42.180429 :B7894C3D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=1876 (legacy spawn) 2025-07-27 05:59:42.186852 :B7894C3E:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:42.187033 :B7894C47: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-27 05:59:42.187902 :B7894C56:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 05:59:48.610197 :B7894E3C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 05:59:48.601 2025-07-27 05:59:48.610199 :B7894E3D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18984 pso_num=26 pso_serial#=222 2025-07-27 05:59:48.610341 :B7894E3E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18984 (legacy spawn) 2025-07-27 05:59:48.616833 :B7894E3F:db_trace:ksl2.c@14083:ksl_update_post_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-27 05:59:48.617018 :B7894E48: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-27 05:59:48.617859 :B7894E49:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-27 06:00:02.053007 :B789544F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-27 06:00:02.042 2025-07-27 06:00:02.053008 :B7895450:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19440 pso_num=26 pso_serial#=223 2025-07-27 06:00:02.053144 :B7895451:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19440 osp_idx=24 osp_ver=690508 osp_pg=0 (spawn #426934) 2025-07-27 06:00:02.053144 :B7895452: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-27 06:00:02.053145 :B7895453:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=54 time=2276777939 2025-07-27 06:00:02.053145 :B7895454:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-27 06:00:02.053146 :B7895455:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-27 06:00:02.053146 :B7895456:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-27 06:00:02.053146 :B7895457:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-27 06:00:02.053147 :B7895458:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-27 06:00:02.053634 :B789545C: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-27 06:00:02.058721 :B7895479:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.058760 :B7895484:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-27 06:00:02.062098 :B789549F:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.442975 :B78957EE:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.486837 :B7895801:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.502814 :B7895809:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.560595 :B789583C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.692305 :B7895844:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.806829 :B789584C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.816865 :B7895854:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.832508 :B789585C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.847991 :B7895864:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.864131 :B789586C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.874045 :B7895874:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.883938 :B789587C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.899444 :B7895884:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.915130 :B789588C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.925441 :B7895894:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.935497 :B789589C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.951026 :B78958A4:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.960258 :B78958AC:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.977120 :B78958B4:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:02.992926 :B78958BC:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.008313 :B78958C4:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.024197 :B78958CC:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.031590 :B78958D4:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.041766 :B78958DC:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.057446 :B78958E4:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.067391 :B78958EC:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.077819 :B78958F4:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.087246 :B78958FC:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.103006 :B7895904:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.112633 :B789590C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.128203 :B7895914:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.135386 :B789591C:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.136283*:B789592E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000052C9A188 estart=1753567202 eid=16803614 ctx=0x0000000783637648 cwh=0x0000000029A40068 cpu=15625 buffg=156 interb=0 r=0 rb=0 w=0 wb=0 2025-07-27 06:00:03.136283*:B789592F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000052C9A188 curCtx=0x0000000052BEFEB8 pubTabIdxCnt=0 2025-07-27 06:00:03.136283*:B7895930:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000052C9A188 2025-07-27 06:00:03.136283*:B7895931:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000052C9A188 2025-07-27 06:00:03.136283*:B7895932:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC288 estart=1753567201 eid=16777341 ctx=0x000000079F654AA8 cwh=0x0000000028BB0158 cpu=1062500 buffg=75660 interb=17334272 r=780 rb=15032320 w=12 wb=2301952 2025-07-27 06:00:03.136283*:B7895933:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC288 curCtx=0x0000000052BEEFE0 pubTabIdxCnt=0 2025-07-27 06:00:03.136283*:B7895934:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC288 2025-07-27 06:00:03.136283*:B7895935:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC288 2025-07-27 06:00:03.136283*:B7895936:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000052C9A188 curCtx=0x0000000052BEFEB8 xsc->flg4=65792 flags=0 2025-07-27 06:00:03.136283*:B7895937:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000052C9A188 2025-07-27 06:00:03.145052 :B7895938:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.160887 :B7895940:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.176518 :B7895948:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.186811 :B7895963:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.202218 :B789596B:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.218025 :B7895973:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.233466 :B789597B:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.243318 :B7895983:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.255845 :B789598B:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.266030 :B7895993:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.275670 :B789599B:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.285384 :B78959A3:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.295467 :B78959B7:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.311291 :B78959BF:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.321154 :B78959C7:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.328613 :B78959CF:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.336054 :B78959D7:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.343424 :B78959DF:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.350838 :B78959E7:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.358310 :B78959EF:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.365862 :B78959F7:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.373319 :B78959FF:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:03.380684 :B7895A07:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:04.538974 :B7895CF7:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:04.539318 :B7895CFF:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:04.539318*:B7895D02:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC288 curCtx=0x0000000052BEEFE0 xsc->flg4=65792 flags=2 2025-07-27 06:00:04.539318*:B7895D03:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC288 2025-07-27 06:00:04.539565 :B7895D09:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-27 06:00:04.544050 :B7895D0A:db_trace:ksl2.c@14083:ksl_update_post_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-27 06:00:04.544130 :B7895D0D: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-27 06:00:04.544212 :B7895D1A: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: 19440, J001)