Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607060008\orcl_j001_13912_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:36806M/63366M, Ph+PgF:43412M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 13912, image: ORACLE.EXE (J001) *** 2025-06-07 06:00:08.818 *** SESSION ID:(209.27383) 2025-06-07 06:00:08.818 *** 2025-06-07 06:00:08.818 Process diagnostic dump for ORACLE.EXE (J001), OS id=13912, pid: 26, proc_ser: 71, sid: 209, sess_ser: 27383 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13912 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.310105 sec, exc=0.310105 sec, total=0.310105 sec wait times: max=0.500000 sec, heur=4.309405 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=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.000008 sec of elapsed time 1: 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.505106 sec, exc=0.505106 sec, total=0.505106 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000012 sec of elapsed time 4: 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.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.494130 sec, exc=0.494130 sec, total=0.494130 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 27383 --------------------------------------------------- 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:08] 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-06-07 06:00:08.818 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 13912, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 05:57:30.756893 :A1635D90:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:57:30.765386 :A1635D93:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:57:30.752 2025-06-07 05:57:30.765388 :A1635D94:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12924 pso_num=26 pso_serial#=56 2025-06-07 05:57:30.765523 :A1635D95:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12924 (legacy spawn) 2025-06-07 05:57:30.772019 :A1635DB1: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-06-07 05:57:30.772190 :A1635DBA: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-06-07 05:57:30.772982 :A1635DBB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:57:31.072419 :A1635DD4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:57:31.064 2025-06-07 05:57:31.072421 :A1635DD5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15736 pso_num=26 pso_serial#=57 2025-06-07 05:57:31.072557 :A1635DD6:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15736 (legacy spawn) 2025-06-07 05:57:31.079035 :A1635DD7: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-06-07 05:57:31.079213 :A1635DE0: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-06-07 05:57:31.080101 :A1635DE1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:57:33.493433 :A1635EA9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:57:33.484 2025-06-07 05:57:33.493435 :A1635EAA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12464 pso_num=26 pso_serial#=58 2025-06-07 05:57:33.493599 :A1635EAB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12464 (legacy spawn) 2025-06-07 05:57:33.500462 :A1635EAC: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-06-07 05:57:33.500641 :A1635EB1: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-06-07 05:57:33.501550 :A1635EB6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:58:29.451685 :A16380D0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:58:29.437 2025-06-07 05:58:29.451688 :A16380D1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10856 pso_num=26 pso_serial#=59 2025-06-07 05:58:29.451825 :A16380D2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10856 (legacy spawn) 2025-06-07 05:58:29.458295 :A16380D3: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-06-07 05:58:29.458468 :A16380D8: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-06-07 05:58:29.459290 :A16380DD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:58:29.467922 :A16380E0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:58:29.452 2025-06-07 05:58:29.467924 :A16380E1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16152 pso_num=26 pso_serial#=60 2025-06-07 05:58:29.468063 :A16380E2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16152 (legacy spawn) 2025-06-07 05:58:29.474596 :A16380E3: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-06-07 05:58:29.474779 :A16380EB: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-06-07 05:58:29.475578 :A16380ED:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:58:32.632418 :A16381E5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:58:32.619 2025-06-07 05:58:32.632420 :A16381E6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15208 pso_num=26 pso_serial#=61 2025-06-07 05:58:32.632562 :A16381E7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15208 (legacy spawn) 2025-06-07 05:58:32.639123 :A16381E8: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-06-07 05:58:32.639307 :A16381ED: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-06-07 05:58:32.640099 :A16381F2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:58:32.648792 :A16381F5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:58:32.634 2025-06-07 05:58:32.648794 :A16381F6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15064 pso_num=26 pso_serial#=62 2025-06-07 05:58:32.648928 :A16381F7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15064 (legacy spawn) 2025-06-07 05:58:32.655473 :A16381F8: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-06-07 05:58:32.655650 :A1638200: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-06-07 05:58:32.656460 :A1638202:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:58:33.230478 :A1638286:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:58:33.223 2025-06-07 05:58:33.230480 :A1638287:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15944 pso_num=26 pso_serial#=63 2025-06-07 05:58:33.230613 :A1638288:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15944 (legacy spawn) 2025-06-07 05:58:33.237061 :A1638289: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-06-07 05:58:33.237241 :A1638292: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-06-07 05:58:33.238080 :A1638293:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:58:41.684989 :A16384EC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:58:41.676 2025-06-07 05:58:41.684991 :A16384ED:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15024 pso_num=26 pso_serial#=64 2025-06-07 05:58:41.685139 :A16384EE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15024 (legacy spawn) 2025-06-07 05:58:41.691614 :A16384EF: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-06-07 05:58:41.691788 :A16384F4: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-06-07 05:58:41.692595 :A16384F9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:59:35.367471 :A163969D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:59:35.359 2025-06-07 05:59:35.367474 :A163969E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12880 pso_num=26 pso_serial#=65 2025-06-07 05:59:35.367610 :A163969F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12880 (legacy spawn) 2025-06-07 05:59:35.374016 :A16396A0: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-06-07 05:59:35.374188 :A16396A5: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-06-07 05:59:35.374992 :A16396AA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:59:36.742723 :A163972B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:59:36.734 2025-06-07 05:59:36.742725 :A163972C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16304 pso_num=26 pso_serial#=66 2025-06-07 05:59:36.742862 :A163972D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16304 (legacy spawn) 2025-06-07 05:59:36.749284 :A163972E: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-06-07 05:59:36.749454 :A1639733: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-06-07 05:59:36.750276 :A1639744:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:59:42.159711 :A16398F7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:59:42.145 2025-06-07 05:59:42.159712 :A16398F8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11272 pso_num=26 pso_serial#=67 2025-06-07 05:59:42.159854 :A16398F9:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11272 (legacy spawn) 2025-06-07 05:59:42.166213 :A16398FA: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-06-07 05:59:42.166385 :A16398FF: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-06-07 05:59:42.167161 :A1639904:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:59:42.175769 :A1639907:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:59:42.161 2025-06-07 05:59:42.175771 :A1639908:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=5896 pso_num=26 pso_serial#=68 2025-06-07 05:59:42.175902 :A1639909:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=5896 (legacy spawn) 2025-06-07 05:59:42.182370 :A163990A: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-06-07 05:59:42.182551 :A1639912: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-06-07 05:59:42.183359 :A1639914:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:59:45.241227 :A1639A0F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:59:45.228 2025-06-07 05:59:45.241229 :A1639A10:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1112 pso_num=26 pso_serial#=69 2025-06-07 05:59:45.241364 :A1639A11:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=1112 (legacy spawn) 2025-06-07 05:59:45.247788 :A1639A12: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-06-07 05:59:45.247956 :A1639A17: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-06-07 05:59:45.248775 :A1639A1C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 05:59:45.257431 :A1639A1F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 05:59:45.244 2025-06-07 05:59:45.257433 :A1639A20:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10760 pso_num=26 pso_serial#=70 2025-06-07 05:59:45.257568 :A1639A21:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10760 (legacy spawn) 2025-06-07 05:59:45.263982 :A1639A22: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-06-07 05:59:45.264150 :A1639A2B: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-06-07 05:59:45.264961 :A1639A2C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 06:00:02.039260 :A163A13A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 06:00:02.028 2025-06-07 06:00:02.039263 :A163A13B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13912 pso_num=26 pso_serial#=71 2025-06-07 06:00:02.039392 :A163A13C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13912 osp_idx=24 osp_ver=405521 osp_pg=0 (spawn #260245) 2025-06-07 06:00:02.039393 :A163A13D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 06:00:02.039393 :A163A13E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=208 time=2251760688 2025-06-07 06:00:02.039393 :A163A13F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 06:00:02.039394 :A163A140:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 06:00:02.039394 :A163A141:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 06:00:02.039395 :A163A142:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 06:00:02.039395 :A163A143:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 06:00:02.039868 :A163A148: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-06-07 06:00:02.045093 :A163A16C: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-06-07 06:00:02.045133 :A163A16F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-06-07 06:00:02.048359 :A163A18A: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-06-07 06:00:02.427605 :A163A4C7: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-06-07 06:00:02.471755 :A163A4CF: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-06-07 06:00:02.487823 :A163A4D7: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-06-07 06:00:02.545304 :A163A4DF: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-06-07 06:00:02.678904 :A163A4F7: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-06-07 06:00:02.793529 :A163A4FF: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-06-07 06:00:02.803454 :A163A513: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-06-07 06:00:02.818242 :A163A51B: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-06-07 06:00:02.832901 :A163A523: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-06-07 06:00:02.847945 :A163A52B: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-06-07 06:00:02.857470 :A163A533: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-06-07 06:00:02.867057 :A163A53B: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-06-07 06:00:02.881775 :A163A543: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-06-07 06:00:02.896658 :A163A54B: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-06-07 06:00:02.906341 :A163A553: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-06-07 06:00:02.915873 :A163A55B: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-06-07 06:00:02.930683 :A163A563: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-06-07 06:00:02.939928 :A163A56B: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-06-07 06:00:02.955036 :A163A573: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-06-07 06:00:02.969729 :A163A57B: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-06-07 06:00:02.984228 :A163A583: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-06-07 06:00:02.991665 :A163A58B: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-06-07 06:00:03.006288 :A163A593: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-06-07 06:00:03.016148 :A163A5C3: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-06-07 06:00:03.030931 :A163A5D8: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-06-07 06:00:03.040282 :A163A5E0: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-06-07 06:00:03.050530 :A163A5E8: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-06-07 06:00:03.065515 :A163A5F0: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-06-07 06:00:03.074872 :A163A5F8: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-06-07 06:00:03.084299 :A163A600: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-06-07 06:00:03.100298 :A163A608: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-06-07 06:00:03.115489 :A163A610: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-06-07 06:00:03.130375 :A163A618: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-06-07 06:00:03.140176 :A163A620: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-06-07 06:00:03.149220 :A163A628: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-06-07 06:00:03.159121 :A163A64A: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-06-07 06:00:03.173875 :A163A666: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-06-07 06:00:03.188937 :A163A66E: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-06-07 06:00:03.198900 :A163A676: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-06-07 06:00:03.214113 :A163A67E: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-06-07 06:00:03.224079 :A163A686: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-06-07 06:00:03.234399 :A163A68E: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-06-07 06:00:03.244299 :A163A696: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-06-07 06:00:03.259226 :A163A69E: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-06-07 06:00:03.271941 :A163A6A6: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-06-07 06:00:03.281847 :A163A6AE: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-06-07 06:00:03.289380 :A163A6B6: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-06-07 06:00:03.296782 :A163A6BE: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-06-07 06:00:03.304118 :A163A6C6: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-06-07 06:00:03.311525 :A163A6CE: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-06-07 06:00:03.318942 :A163A6D6: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-06-07 06:00:03.326581 :A163A6DE: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-06-07 06:00:03.334548 :A163A6E6: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-06-07 06:00:03.342312 :A163A701: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-06-07 06:00:03.349675 :A163A719: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-06-07 06:00:03.357130 :A163A721: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-06-07 06:00:03.638807*:A163A729:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029316918 estart=1749247202 eid=16777367 ctx=0x00000007831EA738 cwh=0x0000000029340248 cpu=281250 buffg=52797 interb=483328 r=14 rb=229376 w=1 wb=253952 2025-06-07 06:00:03.638807*:A163A72A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029316918 curCtx=0x0000000042EF7E08 pubTabIdxCnt=0 2025-06-07 06:00:03.638807*:A163A72B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029316918 2025-06-07 06:00:03.638807*:A163A72C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029316918 2025-06-07 06:00:03.638807*:A163A72D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000272EC288 estart=1749247201 eid=16777291 ctx=0x000000079F654AA8 cwh=0x00000000272E0158 cpu=1546875 buffg=140881 interb=29286400 r=1494 rb=26730496 w=13 wb=2555904 2025-06-07 06:00:03.638807*:A163A72E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000272EC288 curCtx=0x0000000029318510 pubTabIdxCnt=0 2025-06-07 06:00:03.638807*:A163A72F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000272EC288 2025-06-07 06:00:03.638807*:A163A730:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000272EC288 2025-06-07 06:00:03.888811*:A163A84F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029316918 curCtx=0x0000000042EF7E08 xsc->flg4=65792 flags=1 2025-06-07 06:00:03.888811*:A163A850:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029316918 2025-06-07 06:00:04.472611 :A163A8D4: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-06-07 06:00:04.475064 :A163A8DC: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-06-07 06:00:04.475311 :A163A8E4: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-06-07 06:00:04.482929 :A163A8EC: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-06-07 06:00:04.490523 :A163A8F4: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-06-07 06:00:04.497974 :A163A8FC: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-06-07 06:00:04.506503 :A163A904: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-06-07 06:00:04.506885 :A163A90C: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-06-07 06:00:04.506885*:A163A910:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000272EC288 curCtx=0x0000000029318510 xsc->flg4=65792 flags=2 2025-06-07 06:00:04.506885*:A163A912:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000272EC288 2025-06-07 06:00:04.507144 :A163A915:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-06-07 06:00:04.511585 :A163A916: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-06-07 06:00:04.511670 :A163A919: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-06-07 06:00:04.511762 :A163A926: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: 13912, J001)