Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823060010\orcl_j001_20068_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:35295M/63366M, Ph+PgF:40459M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 20068, image: ORACLE.EXE (J001) *** 2025-08-23 06:00:10.163 *** SESSION ID:(219.6619) 2025-08-23 06:00:10.163 *** 2025-08-23 06:00:10.163 Process diagnostic dump for ORACLE.EXE (J001), OS id=20068, pid: 27, proc_ser: 240, sid: 219, sess_ser: 6619 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20068 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.218592 sec, exc=0.218592 sec, total=0.218592 sec wait times: max=0.500000 sec, heur=5.716206 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=10 seq_num=11 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.501068 sec, exc=0.501068 sec, total=0.501068 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=8 seq_num=9 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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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 4: 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.000013 sec of elapsed time 5: 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 6: 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500103 sec, exc=0.500103 sec, total=0.500103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 219 serial 6619 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 06:00:04 - 06:00:10] idle wait at each sample [session created at: 06:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-08-23 06:00:10.178 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 20068, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 05:57:25.049037 :C3727FEB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:57:28.096670 :C37280CF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:57:28.084 2025-08-23 05:57:28.096672 :C37280D0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21692 pso_num=27 pso_serial#=225 2025-08-23 05:57:28.096805 :C37280D1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21692 (legacy spawn) 2025-08-23 05:57:28.103318 :C37280D2: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-23 05:57:28.103481 :C37280D7: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-23 05:57:28.104251 :C37280DC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:57:28.113065 :C37280DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:57:28.099 2025-08-23 05:57:28.113067 :C37280E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19576 pso_num=27 pso_serial#=226 2025-08-23 05:57:28.113199 :C37280E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19576 (legacy spawn) 2025-08-23 05:57:28.119795 :C37280EE: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-23 05:57:28.119953 :C37280F7: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-23 05:57:28.120735 :C37280F8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:57:43.374765 :C37285C6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:57:43.362 2025-08-23 05:57:43.374767 :C37285C7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21344 pso_num=27 pso_serial#=227 2025-08-23 05:57:43.374906 :C37285C8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21344 (legacy spawn) 2025-08-23 05:57:43.381430 :C37285C9: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-23 05:57:43.381605 :C37285CE: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-23 05:57:43.382426 :C37285D3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:57:44.823237 :C3728631:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:57:44.823 2025-08-23 05:57:44.823240 :C3728632:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21668 pso_num=27 pso_serial#=228 2025-08-23 05:57:44.823380 :C3728633:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21668 (legacy spawn) 2025-08-23 05:57:44.829858 :C3728634: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-23 05:57:44.830031 :C372863D: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-23 05:57:44.830815 :C372863E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:58:14.464402 :C3728FED:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:58:14.456 2025-08-23 05:58:14.464405 :C3728FEE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19848 pso_num=27 pso_serial#=229 2025-08-23 05:58:14.464552 :C3728FEF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19848 (legacy spawn) 2025-08-23 05:58:14.471037 :C3728FF0: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-23 05:58:14.471205 :C3728FF9: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-23 05:58:14.472064 :C372900D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:58:19.019201 :C3729183:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:58:19.010 2025-08-23 05:58:19.019203 :C3729184:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21796 pso_num=27 pso_serial#=230 2025-08-23 05:58:19.019344 :C3729185:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21796 (legacy spawn) 2025-08-23 05:58:19.043412 :C3729186: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-23 05:58:19.043582 :C372918B: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-23 05:58:19.044473 :C3729190:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:58:37.595670 :C372975A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:58:37.581 2025-08-23 05:58:37.595672 :C372975B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16984 pso_num=27 pso_serial#=231 2025-08-23 05:58:37.595809 :C372975C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16984 (legacy spawn) 2025-08-23 05:58:37.602274 :C372975D: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-23 05:58:37.602455 :C3729762: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-23 05:58:37.603237 :C3729767:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:58:37.611976 :C372976A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:58:37.597 2025-08-23 05:58:37.611978 :C372976B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19264 pso_num=27 pso_serial#=232 2025-08-23 05:58:37.612116 :C372976C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19264 (legacy spawn) 2025-08-23 05:58:37.618541 :C372976D: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-23 05:58:37.618711 :C3729776: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-23 05:58:37.619522 :C3729777:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:58:40.787245 :C372986B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:58:40.774 2025-08-23 05:58:40.787247 :C372986C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=22520 pso_num=27 pso_serial#=233 2025-08-23 05:58:40.787389 :C372986D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=22520 (legacy spawn) 2025-08-23 05:58:40.793825 :C372986E: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-23 05:58:40.794001 :C3729873: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-23 05:58:40.806008 :C3729878:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:58:40.814653 :C372987B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:58:40.805 2025-08-23 05:58:40.814655 :C372987C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14012 pso_num=27 pso_serial#=234 2025-08-23 05:58:40.814792 :C372987D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14012 (legacy spawn) 2025-08-23 05:58:40.821260 :C372987E: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-23 05:58:40.821417 :C3729887: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-23 05:58:40.822231 :C3729888:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:59:16.611400 :C372A49F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:59:16.603 2025-08-23 05:59:16.611402 :C372A4A0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16996 pso_num=27 pso_serial#=235 2025-08-23 05:59:16.611543 :C372A4A1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16996 (legacy spawn) 2025-08-23 05:59:16.618055 :C372A4A2: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-23 05:59:16.618224 :C372A4A7: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-23 05:59:16.619077 :C372A4AC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:59:16.975693 :C372A4AF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:59:16.963 2025-08-23 05:59:16.975695 :C372A4B0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20932 pso_num=27 pso_serial#=236 2025-08-23 05:59:16.975832 :C372A4B1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20932 (legacy spawn) 2025-08-23 05:59:16.982601 :C372A4BD: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-23 05:59:16.982768 :C372A4C6: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-23 05:59:16.983542 :C372A4C7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:59:16.992199 :C372A4CA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:59:16.978 2025-08-23 05:59:16.992201 :C372A4CB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20116 pso_num=27 pso_serial#=237 2025-08-23 05:59:16.992340 :C372A4CC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20116 (legacy spawn) 2025-08-23 05:59:16.998841 :C372A4CD: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-23 05:59:16.999009 :C372A4D6: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-23 05:59:16.999813 :C372A4D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:59:27.119914 :C372A7F4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:59:27.110 2025-08-23 05:59:27.119916 :C372A7F5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19952 pso_num=27 pso_serial#=238 2025-08-23 05:59:27.120061 :C372A7F6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19952 (legacy spawn) 2025-08-23 05:59:27.126620 :C372A7F7: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-23 05:59:27.126783 :C372A800: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-23 05:59:27.127580 :C372A801:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 05:59:38.242297 :C372AB60:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 05:59:38.234 2025-08-23 05:59:38.242299 :C372AB61:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16876 pso_num=27 pso_serial#=239 2025-08-23 05:59:38.242435 :C372AB62:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16876 (legacy spawn) 2025-08-23 05:59:38.248857 :C372AB63: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-23 05:59:38.249029 :C372AB68: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-23 05:59:38.249867 :C372AB6D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 06:00:02.041155 :C372B51D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 06:00:02.030 2025-08-23 06:00:02.041157 :C372B51E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20068 pso_num=27 pso_serial#=240 2025-08-23 06:00:02.041297 :C372B51F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=20068 osp_idx=25 osp_ver=330728 osp_pg=0 (spawn #516923) 2025-08-23 06:00:02.041298 :C372B520: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-23 06:00:02.041299 :C372B521:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=73 time=314504049 2025-08-23 06:00:02.041299 :C372B522:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 06:00:02.041300 :C372B523:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 06:00:02.041301 :C372B524:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 06:00:02.041301 :C372B525:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 06:00:02.041301 :C372B526:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 06:00:02.041788 :C372B527: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-23 06:00:02.047046 :C372B54F: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-23 06:00:02.047087 :C372B552:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-08-23 06:00:02.050433 :C372B56D: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-23 06:00:02.369610 :C372B887: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-23 06:00:02.413320 :C372B88F: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-23 06:00:02.429104 :C372B897: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-23 06:00:02.486864 :C372B8A7: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-23 06:00:02.619803 :C372B8BB: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-23 06:00:02.632989 :C372B8C3: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-23 06:00:02.643524 :C372B8CB: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-23 06:00:02.659682 :C372B8D3: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-23 06:00:02.675427 :C372B8DB: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-23 06:00:02.691889 :C372B8E3: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-23 06:00:02.701862 :C372B8EB: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-23 06:00:02.717747 :C372B8F3: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-23 06:00:02.727310 :C372B90E: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-23 06:00:02.743451 :C372B916: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-23 06:00:02.753915 :C372B91E: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-23 06:00:02.763557 :C372B926: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-23 06:00:02.779480 :C372B92E: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-23 06:00:02.789505 :C372B936: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-23 06:00:02.806142 :C372B93E: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-23 06:00:02.823276 :C372B969: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-23 06:00:02.839563 :C372B971: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-23 06:00:02.855690 :C372B979: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-23 06:00:02.866153 :C372B981: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-23 06:00:02.882406 :C372B989: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-23 06:00:02.892501 :C372B991: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-23 06:00:02.903040 :C372B999: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-23 06:00:02.919322 :C372B9A1: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-23 06:00:02.929773 :C372B9A9: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-23 06:00:02.945673 :C372B9B1: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-23 06:00:02.952962 :C372B9B9: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-23 06:00:02.962366 :C372B9C1: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-23 06:00:02.972909 :C372B9C9: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-23 06:00:02.989619 :C372B9D1: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-23 06:00:03.005449 :C372B9D9: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-23 06:00:03.013217 :C372B9E1: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-23 06:00:03.022671 :C372B9E9: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-23 06:00:03.032839 :C372B9F1: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-23 06:00:03.048769 :C372B9F9: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-23 06:00:03.064405 :C372BA01: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-23 06:00:03.076962 :C372BA09: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-23 06:00:03.092721 :C372BA11: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-23 06:00:03.103264 :C372BA19: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-23 06:00:03.113077 :C372BA21: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-23 06:00:03.123008 :C372BA29: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-23 06:00:03.132880 :C372BA31: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-23 06:00:03.149251 :C372BA39: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-23 06:00:03.156839 :C372BA41: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-23 06:00:03.164540 :C372BA49: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-23 06:00:03.172431 :C372BA51: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-23 06:00:03.180250 :C372BA59: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-23 06:00:03.187893 :C372BA61: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-23 06:00:03.195914 :C372BA69: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-23 06:00:03.203700 :C372BA71: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-23 06:00:03.211635 :C372BA79: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-23 06:00:03.219176 :C372BA81: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-23 06:00:03.226690 :C372BA89: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-23 06:00:03.234113 :C372BA91: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-23 06:00:03.241683 :C372BA99: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-23 06:00:03.249140 :C372BAA1: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-23 06:00:03.552462*:C372BACF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299C6918 estart=1755900002 eid=16777521 ctx=0x00000007831EA738 cwh=0x0000000029990068 cpu=296875 buffg=54994 interb=483328 r=14 rb=229376 w=1 wb=253952 2025-08-23 06:00:03.552462*:C372BAD2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299C6918 curCtx=0x00000000299C8338 pubTabIdxCnt=0 2025-08-23 06:00:03.552462*:C372BAD3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299C6918 2025-08-23 06:00:03.552462*:C372BAD5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299C6918 2025-08-23 06:00:03.552462*:C372BAD7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B6C288 estart=1755900001 eid=16777368 ctx=0x000000079F654AA8 cwh=0x0000000026B60158 cpu=1437500 buffg=143277 interb=30695424 r=1578 rb=28123136 w=13 wb=2572288 2025-08-23 06:00:03.552462*:C372BADA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B6C288 curCtx=0x0000000029A034D8 pubTabIdxCnt=0 2025-08-23 06:00:03.552462*:C372BADB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B6C288 2025-08-23 06:00:03.552462*:C372BADC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B6C288 2025-08-23 06:00:03.802476*:C372BC64:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299C6918 curCtx=0x00000000299C8338 xsc->flg4=65792 flags=1 2025-08-23 06:00:03.802476*:C372BC65:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299C6918 2025-08-23 06:00:04.081972 :C372BC9C:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 4, ext 1, nex 3 2025-08-23 06:00:04.081978 :C372BC9D:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x008127cb where: kduwh01: kdusru 2025-08-23 06:00:04.452276 :C372BCC7: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-23 06:00:04.452618 :C372BCCF: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-23 06:00:04.452618*:C372BCD2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B6C288 curCtx=0x0000000029A034D8 xsc->flg4=65792 flags=2 2025-08-23 06:00:04.452618*:C372BCD3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B6C288 2025-08-23 06:00:04.452875 :C372BCD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-08-23 06:00:04.457224 :C372BCDA: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-23 06:00:04.457312 :C372BCDD: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-23 06:00:04.457387 :C372BCEA: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 20068, J001)