Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250617220009\orcl_j001_13784_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:36534M/63366M, Ph+PgF:42928M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 13784, image: ORACLE.EXE (J001) *** 2025-06-17 22:00:09.231 *** SESSION ID:(209.10103) 2025-06-17 22:00:09.231 *** 2025-06-17 22:00:09.231 Process diagnostic dump for ORACLE.EXE (J001), OS id=13784, pid: 26, proc_ser: 10, sid: 209, sess_ser: 10103 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13784 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.245096 sec, exc=0.245096 sec, total=0.245096 sec wait times: max=0.500000 sec, heur=4.748346 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.000012 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.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.000010 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.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.510084 sec, exc=0.510084 sec, total=0.510084 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.505115 sec, exc=0.505115 sec, total=0.505115 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.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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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 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.000008 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.487972 sec, exc=0.487972 sec, total=0.487972 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 10103 --------------------------------------------------- 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, 22:00:04 - 22:00:09] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-17 22:00:09.231 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 13784, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-17 21:57:33.246895 :A61255E0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:57:48.888303 :A6125AAC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:57:48.880 2025-06-17 21:57:48.888306 :A6125AAD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12936 pso_num=26 pso_serial#=250 2025-06-17 21:57:48.888445 :A6125AAE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12936 (legacy spawn) 2025-06-17 21:57:48.894988 :A6125AAF: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-17 21:57:48.895160 :A6125AB5: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-17 21:57:48.895983 :A6125AB9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:57:51.792038 :A6125BA6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:57:51.777 2025-06-17 21:57:51.792040 :A6125BA7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15364 pso_num=26 pso_serial#=251 2025-06-17 21:57:51.792170 :A6125BA8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15364 (legacy spawn) 2025-06-17 21:57:51.798808 :A6125BA9: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-17 21:57:51.798994 :A6125BB2: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-17 21:57:51.799835 :A6125BB3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:57:51.808783 :A6125BB6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:57:51.809 2025-06-17 21:57:51.808785 :A6125BB7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16740 pso_num=26 pso_serial#=252 2025-06-17 21:57:51.808927 :A6125BB8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16740 (legacy spawn) 2025-06-17 21:57:51.815605 :A6125BB9: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-17 21:57:51.815783 :A6125BC2: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-17 21:57:51.816589 :A6125BC3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:58:28.393121 :A6126782:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:58:28.390 2025-06-17 21:58:28.393123 :A6126783:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17016 pso_num=26 pso_serial#=253 2025-06-17 21:58:28.393264 :A6126784:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17016 (legacy spawn) 2025-06-17 21:58:28.399658 :A6126785: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-17 21:58:28.399688 :A6126788: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-17 21:58:28.399831 :A612678B: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-17 21:58:28.400615 :A612679C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:58:28.430240 :A612679F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:58:28.421 2025-06-17 21:58:28.430243 :A61267A0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14020 pso_num=26 pso_serial#=254 2025-06-17 21:58:28.430382 :A61267A1:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14020 (legacy spawn) 2025-06-17 21:58:28.437240 :A61267A2: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-17 21:58:28.437414 :A61267AB: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-17 21:58:28.438319 :A61267AC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:58:31.501400 :A61268E6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:58:31.488 2025-06-17 21:58:31.501403 :A61268E7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16580 pso_num=26 pso_serial#=255 2025-06-17 21:58:31.501553 :A61268E8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16580 (legacy spawn) 2025-06-17 21:58:31.508484 :A61268E9: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-17 21:58:31.508671 :A61268EE: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-17 21:58:31.509483 :A61268F3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:58:31.518100 :A61268F6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:58:31.504 2025-06-17 21:58:31.518102 :A61268F7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14940 pso_num=26 pso_serial#=1 2025-06-17 21:58:31.518236 :A61268F8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14940 (legacy spawn) 2025-06-17 21:58:31.524671 :A61268F9: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-17 21:58:31.524831 :A6126902: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-17 21:58:31.525619 :A6126903:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:58:41.744023 :A6126BDC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:58:41.736 2025-06-17 21:58:41.744025 :A6126BDD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14704 pso_num=26 pso_serial#=2 2025-06-17 21:58:41.744195 :A6126BDE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14704 (legacy spawn) 2025-06-17 21:58:41.750627 :A6126BDF: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-17 21:58:41.750816 :A6126BE4: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-17 21:58:41.751656 :A6126BE9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:58:51.079040 :A6126ECC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:58:51.071 2025-06-17 21:58:51.079042 :A6126ECD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17160 pso_num=26 pso_serial#=3 2025-06-17 21:58:51.079192 :A6126ECE:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17160 (legacy spawn) 2025-06-17 21:58:51.086060 :A6126ECF: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-17 21:58:51.086246 :A6126ED4: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-17 21:58:51.087184 :A6126EE7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:59:40.910473 :A6127F05:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:59:40.907 2025-06-17 21:59:40.910476 :A6127F06:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15496 pso_num=26 pso_serial#=4 2025-06-17 21:59:40.910615 :A6127F07:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15496 (legacy spawn) 2025-06-17 21:59:40.917112 :A6127F08: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-17 21:59:40.917286 :A6127F0D: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-17 21:59:40.918078 :A6127F12:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:59:40.926818 :A6127F15:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:59:40.923 2025-06-17 21:59:40.926821 :A6127F16:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8928 pso_num=26 pso_serial#=5 2025-06-17 21:59:40.926961 :A6127F17:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8928 (legacy spawn) 2025-06-17 21:59:40.933487 :A6127F18: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-17 21:59:40.933660 :A6127F21: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-17 21:59:40.934434 :A6127F22:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:59:44.008647 :A6128003:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:59:43.995 2025-06-17 21:59:44.008650 :A6128004:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9628 pso_num=26 pso_serial#=6 2025-06-17 21:59:44.008788 :A6128005:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9628 (legacy spawn) 2025-06-17 21:59:44.015291 :A6128006: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-17 21:59:44.015467 :A612800B: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-17 21:59:44.016272 :A6128010:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:59:44.035630 :A6128013:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:59:44.026 2025-06-17 21:59:44.035632 :A6128014:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12380 pso_num=26 pso_serial#=7 2025-06-17 21:59:44.035786 :A6128015:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12380 (legacy spawn) 2025-06-17 21:59:44.042088 :A6128016: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-17 21:59:44.042273 :A612801F: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-17 21:59:44.043099 :A6128020:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:59:49.654659 :A61281C1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:59:49.645 2025-06-17 21:59:49.654661 :A61281C2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9804 pso_num=26 pso_serial#=8 2025-06-17 21:59:49.654805 :A61281C3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9804 (legacy spawn) 2025-06-17 21:59:49.661505 :A61281F0: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-17 21:59:49.661676 :A61281F8: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-17 21:59:49.662524 :A61281FA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 21:59:53.235556 :A61282ED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 21:59:53.227 2025-06-17 21:59:53.235558 :A61282EE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16500 pso_num=26 pso_serial#=9 2025-06-17 21:59:53.235699 :A61282EF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16500 (legacy spawn) 2025-06-17 21:59:53.242142 :A61282F0: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-17 21:59:53.242317 :A61282F5: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-17 21:59:53.243114 :A61282FA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-17 22:00:02.037043 :A6128755:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-17 22:00:02.026 2025-06-17 22:00:02.037045 :A6128756:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13784 pso_num=26 pso_serial#=10 2025-06-17 22:00:02.037181 :A6128757:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13784 osp_idx=24 osp_ver=462325 osp_pg=0 (spawn #295823) 2025-06-17 22:00:02.037182 :A6128758: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-17 22:00:02.037182 :A6128759:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=85 time=3173345219 2025-06-17 22:00:02.037182 :A612875A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-17 22:00:02.037183 :A612875B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-17 22:00:02.037183 :A612875C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-17 22:00:02.037184 :A612875D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-17 22:00:02.037184 :A612875E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-17 22:00:02.037655 :A6128763: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-17 22:00:02.042657 :A6128787: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-17 22:00:02.042699 :A612878A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-06-17 22:00:02.046150 :A61287A5: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-17 22:00:02.426297 :A6128ACD: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-17 22:00:02.474316 :A6128AD5: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-17 22:00:02.491002 :A6128ADD: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-17 22:00:02.548937 :A6128AED: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-17 22:00:02.680744 :A6128AFD: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-17 22:00:02.796770 :A6128B05: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-17 22:00:02.806559 :A6128B0D: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-17 22:00:02.822228 :A6128B15: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-17 22:00:02.837176 :A6128B1D: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-17 22:00:02.852582 :A6128B25: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-17 22:00:02.861862 :A6128B2D: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-17 22:00:02.871617 :A6128B35: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-17 22:00:02.886631 :A6128B49: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-17 22:00:02.901687 :A6128B51: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-17 22:00:02.911031 :A6128B59: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-17 22:00:02.920476 :A6128B61: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-17 22:00:02.935158 :A6128B69: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-17 22:00:02.944245 :A6128B71: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-17 22:00:02.959922 :A6128B79: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-17 22:00:02.975018 :A6128B81: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-17 22:00:02.989847 :A6128B89: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-17 22:00:03.004776 :A6128BBA: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-17 22:00:03.012268 :A6128BC2: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-17 22:00:03.021989 :A6128BCA: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-17 22:00:03.036914 :A6128BD2: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-17 22:00:03.046605 :A6128BDA: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-17 22:00:03.056228 :A6128BE2: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-17 22:00:03.071254 :A6128BEA: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-17 22:00:03.080280 :A6128BF2: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-17 22:00:03.095215 :A6128BFA: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-17 22:00:03.104239 :A6128C02: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-17 22:00:03.111236 :A6128C0A: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-17 22:00:03.120667 :A6128C12: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-17 22:00:03.135581 :A6128C1A: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-17 22:00:03.150600 :A6128C22: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-17 22:00:03.160599 :A6128C2A: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-17 22:00:03.169996 :A6128C32: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-17 22:00:03.185036 :A6128C3A: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-17 22:00:03.199944 :A6128C42: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-17 22:00:03.215193 :A6128C5D: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-17 22:00:03.224587 :A6128C65: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-17 22:00:03.234798 :A6128C6D: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-17 22:00:03.244132 :A6128C75: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-17 22:00:03.253614 :A6128C7D: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-17 22:00:03.268538 :A6128C85: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-17 22:00:03.277990 :A6128C8D: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-17 22:00:03.285270 :A6128C95: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-17 22:00:03.292498 :A6128C9D: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-17 22:00:03.299892 :A6128CA5: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-17 22:00:03.307221 :A6128CAD: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-17 22:00:03.314227 :A6128CB5: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-17 22:00:03.321438 :A6128CBD: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-17 22:00:03.328935 :A6128CC5: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-17 22:00:03.336593 :A6128CCD: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-17 22:00:03.345231 :A6128CD5: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-17 22:00:03.352662 :A6128CDD: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-17 22:00:03.775814*:A6128D15:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000292D6918 estart=1750168802 eid=16777387 ctx=0x00000007831EA738 cwh=0x0000000029300248 cpu=31250 buffg=31 interb=1040384 r=1 rb=1040384 w=0 wb=0 2025-06-17 22:00:03.775814*:A6128D16:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000292D6918 curCtx=0x000000004666ED68 pubTabIdxCnt=0 2025-06-17 22:00:03.775814*:A6128D17:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000292D6918 2025-06-17 22:00:03.775814*:A6128D18:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000292D6918 2025-06-17 22:00:03.775814*:A6128D19:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000275EC288 estart=1750168801 eid=16777301 ctx=0x000000079F654AA8 cwh=0x00000000275E0158 cpu=1671875 buffg=142186 interb=32989184 r=1496 rb=28418048 w=24 wb=4571136 2025-06-17 22:00:03.775814*:A6128D1A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000275EC288 curCtx=0x00000000292D8510 pubTabIdxCnt=0 2025-06-17 22:00:03.775814*:A6128D1B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000275EC288 2025-06-17 22:00:03.775814*:A6128D1C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000275EC288 2025-06-17 22:00:03.885228*:A6128EA0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000292D6918 curCtx=0x000000004666ED68 xsc->flg4=65792 flags=1 2025-06-17 22:00:03.885228*:A6128EA1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000292D6918 2025-06-17 22:00:04.451621 :A6128FDA: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-17 22:00:04.454427 :A6128FE2: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-17 22:00:04.454659 :A6128FEA: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-17 22:00:04.462545 :A6128FF2: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-17 22:00:04.470547 :A6129005: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-17 22:00:04.478819 :A612900D: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-17 22:00:04.479157 :A6129015: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-17 22:00:04.479157*:A612901D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000275EC288 curCtx=0x00000000292D8510 xsc->flg4=65792 flags=2 2025-06-17 22:00:04.479157*:A612901E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000275EC288 2025-06-17 22:00:04.479419 :A612901F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-06-17 22:00:04.483768 :A6129020: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-17 22:00:04.483843 :A6129023: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-17 22:00:04.483950 :A6129030: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: 13784, J001)