Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250715220007\orcl_j001_17796_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:36294M/63366M, Ph+PgF:42237M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 17796, image: ORACLE.EXE (J001) *** 2025-07-15 22:00:07.697 *** SESSION ID:(209.7675) 2025-07-15 22:00:07.697 *** 2025-07-15 22:00:07.697 Process diagnostic dump for ORACLE.EXE (J001), OS id=17796, pid: 26, proc_ser: 178, sid: 209, sess_ser: 7675 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17796 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.180418 sec, exc=0.180418 sec, total=0.180418 sec wait times: max=0.500000 sec, heur=4.612619 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.000007 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.499993 sec, exc=0.499993 sec, total=0.499993 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.000007 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.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.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510107 sec, exc=0.510107 sec, total=0.510107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 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.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 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.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.000007 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.499247 sec, exc=0.499247 sec, total=0.499247 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 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.438238 sec, exc=0.438238 sec, total=0.438238 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.484523 sec, exc=0.484523 sec, total=0.484523 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 7675 --------------------------------------------------- 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:03 - 22:00:07] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-15 22:00:07.697 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 17796, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-15 21:57:28.295982 :B27D925F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:57:28.281 2025-07-15 21:57:28.295984 :B27D9260:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15316 pso_num=26 pso_serial#=162 2025-07-15 21:57:28.296125 :B27D9261:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15316 (legacy spawn) 2025-07-15 21:57:28.302872 :B27D9262:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:57:28.303049 :B27D9267:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:57:28.303894 :B27D926C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:57:28.313050 :B27D926F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:57:28.313 2025-07-15 21:57:28.313052 :B27D9270:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16572 pso_num=26 pso_serial#=163 2025-07-15 21:57:28.313204 :B27D9271:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16572 (legacy spawn) 2025-07-15 21:57:28.319998 :B27D9272:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:57:28.320180 :B27D927B:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:57:28.321070 :B27D927C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:57:31.076690 :B27D937C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:57:31.068 2025-07-15 21:57:31.076692 :B27D937D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14080 pso_num=26 pso_serial#=164 2025-07-15 21:57:31.076844 :B27D937E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14080 (legacy spawn) 2025-07-15 21:57:31.083461 :B27D937F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:57:31.083662 :B27D9384:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:57:31.084474 :B27D9389:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:57:31.347078 :B27D938C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:57:31.334 2025-07-15 21:57:31.347080 :B27D938D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14784 pso_num=26 pso_serial#=165 2025-07-15 21:57:31.347252 :B27D938E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14784 (legacy spawn) 2025-07-15 21:57:31.353678 :B27D938F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:57:31.353874 :B27D9398:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:57:31.354731 :B27D9399:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:57:31.373931 :B27D939C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:57:31.365 2025-07-15 21:57:31.373933 :B27D939D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18044 pso_num=26 pso_serial#=166 2025-07-15 21:57:31.374074 :B27D939E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18044 (legacy spawn) 2025-07-15 21:57:31.380554 :B27D939F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:57:31.380717 :B27D93A8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:57:31.381509 :B27D93A9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:57:42.839794 :B27D9748:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:57:42.832 2025-07-15 21:57:42.839796 :B27D9749:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18160 pso_num=26 pso_serial#=167 2025-07-15 21:57:42.839935 :B27D974A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18160 (legacy spawn) 2025-07-15 21:57:42.846480 :B27D974B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:57:42.846654 :B27D9754:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:57:42.847491 :B27D9755:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:58:39.121963 :B27DAA9D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:58:39.113 2025-07-15 21:58:39.121965 :B27DAA9E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4696 pso_num=26 pso_serial#=168 2025-07-15 21:58:39.122104 :B27DAA9F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=4696 (legacy spawn) 2025-07-15 21:58:39.128580 :B27DAAA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:58:39.128760 :B27DAAA8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:58:39.129588 :B27DAAAA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:58:40.919245 :B27DAAFE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:58:40.914 2025-07-15 21:58:40.919247 :B27DAAFF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12040 pso_num=26 pso_serial#=169 2025-07-15 21:58:40.919382 :B27DAB00:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12040 (legacy spawn) 2025-07-15 21:58:40.926013 :B27DAB01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:58:40.926192 :B27DAB06:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:58:40.927012 :B27DAB0B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:58:40.935665 :B27DAB0E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:58:40.930 2025-07-15 21:58:40.935667 :B27DAB0F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13572 pso_num=26 pso_serial#=170 2025-07-15 21:58:40.935799 :B27DAB10:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13572 (legacy spawn) 2025-07-15 21:58:40.942249 :B27DAB11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:58:40.942410 :B27DAB1A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:58:40.943198 :B27DAB1B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:58:44.189727 :B27DAC07:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:58:44.177 2025-07-15 21:58:44.189730 :B27DAC08:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=5284 pso_num=26 pso_serial#=171 2025-07-15 21:58:44.189868 :B27DAC09:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=5284 (legacy spawn) 2025-07-15 21:58:44.196377 :B27DAC0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:58:44.196553 :B27DAC0F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:58:44.197350 :B27DAC14:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:58:44.214944 :B27DAC17:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:58:44.208 2025-07-15 21:58:44.214946 :B27DAC18:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12112 pso_num=26 pso_serial#=172 2025-07-15 21:58:44.215085 :B27DAC19:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12112 (legacy spawn) 2025-07-15 21:58:44.221574 :B27DAC1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:58:44.221737 :B27DAC20:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:58:44.222560 :B27DAC24:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:58:45.013621 :B27DAC88:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:58:45.005 2025-07-15 21:58:45.013623 :B27DAC89:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9372 pso_num=26 pso_serial#=173 2025-07-15 21:58:45.013770 :B27DAC8A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9372 (legacy spawn) 2025-07-15 21:58:45.020260 :B27DAC8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:58:45.020427 :B27DAC94:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:58:45.021237 :B27DAC95:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:59:20.611057 :B27DB7F5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:59:20.597 2025-07-15 21:59:20.611059 :B27DB7F6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4800 pso_num=26 pso_serial#=174 2025-07-15 21:59:20.611195 :B27DB7F7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=4800 (legacy spawn) 2025-07-15 21:59:20.617650 :B27DB7F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:59:20.617815 :B27DB800:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:59:20.618641 :B27DB802:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:59:20.627338 :B27DB805:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:59:20.613 2025-07-15 21:59:20.627340 :B27DB806:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15216 pso_num=26 pso_serial#=175 2025-07-15 21:59:20.627474 :B27DB807:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15216 (legacy spawn) 2025-07-15 21:59:20.633956 :B27DB808:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:59:20.634119 :B27DB811:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:59:20.634916 :B27DB812:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:59:47.158441 :B27DC036:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:59:47.150 2025-07-15 21:59:47.158443 :B27DC037:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17592 pso_num=26 pso_serial#=176 2025-07-15 21:59:47.158578 :B27DC038:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17592 (legacy spawn) 2025-07-15 21:59:47.165100 :B27DC039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:59:47.165267 :B27DC03E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:59:47.166096 :B27DC043:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 21:59:47.440180 :B27DC046:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 21:59:47.432 2025-07-15 21:59:47.440182 :B27DC047:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3280 pso_num=26 pso_serial#=177 2025-07-15 21:59:47.440323 :B27DC048:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=3280 (legacy spawn) 2025-07-15 21:59:47.446934 :B27DC049:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 21:59:47.447098 :B27DC052:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 21:59:47.447906 :B27DC053:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-15 22:00:00.630784 :B27DC6A3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-15 22:00:00.619 2025-07-15 22:00:00.630786 :B27DC6A4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17796 pso_num=26 pso_serial#=178 2025-07-15 22:00:00.630926 :B27DC6A5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17796 osp_idx=24 osp_ver=624673 osp_pg=0 (spawn #389168) 2025-07-15 22:00:00.630926 :B27DC6A6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 22:00:00.630927 :B27DC6A7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=84 time=1297576517 2025-07-15 22:00:00.630927 :B27DC6A8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 22:00:00.630927 :B27DC6A9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 22:00:00.630928 :B27DC6AA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 22:00:00.630928 :B27DC6AB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 22:00:00.630928 :B27DC6AC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 22:00:00.631418 :B27DC6B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.636402 :B27DC6D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.636440 :B27DC6D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-15 22:00:00.639879 :B27DC6F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.022007 :B27DCA15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.066165 :B27DCA1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.082064 :B27DCA2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.139531 :B27DCA35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.273467 :B27DCA45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.388764 :B27DCA58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.398405 :B27DCA60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.413946 :B27DCA68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.429325 :B27DCA70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.445303 :B27DCA78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.454663 :B27DCA80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.469939 :B27DCA88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.479621 :B27DCA90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.495300 :B27DCA98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.504965 :B27DCAA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.514786 :B27DCAA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.530306 :B27DCAB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.539765 :B27DCAB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.555657 :B27DCAC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.571547 :B27DCAC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.587085 :B27DCAD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.594957 :B27DCAD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.610518 :B27DCAE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.620022 :B27DCAE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.635653 :B27DCAF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.645194 :B27DCAF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.654205 :B27DCB00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.663924 :B27DCB08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.679732 :B27DCB10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.688935 :B27DCB18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.704367 :B27DCB20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.720180 :B27DCB28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.735582 :B27DCB30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.745084 :B27DCB38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.754501 :B27DCB40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.769652 :B27DCB48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.785441 :B27DCB50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.795216 :B27DCB58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.810571 :B27DCB60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.820743 :B27DCB68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.832844 :B27DCB70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.842138 :B27DCB84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.851224 :B27DCB8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.860861 :B27DCB94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.876583 :B27DCB9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.886128 :B27DCBA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.893321 :B27DCBAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.900884 :B27DCBB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.908360 :B27DCBBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.915725 :B27DCBC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.923207 :B27DCBCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.930713 :B27DCBD4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:01.938187 :B27DCBDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:02.379331*:B27DCC28:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029A46918 estart=1752588001 eid=16777443 ctx=0x00000007831EA738 cwh=0x0000000029A70248 cpu=15625 buffg=21 interb=1040384 r=1 rb=1040384 w=0 wb=0 2025-07-15 22:00:02.379331*:B27DCC29:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029A46918 curCtx=0x000000004ED40E18 pubTabIdxCnt=0 2025-07-15 22:00:02.379331*:B27DCC2A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029A46918 2025-07-15 22:00:02.379331*:B27DCC2B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029A46918 2025-07-15 22:00:02.379331*:B27DCC2C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC288 estart=1752588000 eid=16777329 ctx=0x000000079F654AA8 cwh=0x0000000028BB0158 cpu=1687500 buffg=142786 interb=31670272 r=1412 rb=27066368 w=24 wb=4603904 2025-07-15 22:00:02.379331*:B27DCC2D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC288 curCtx=0x0000000029A48510 pubTabIdxCnt=0 2025-07-15 22:00:02.379331*:B27DCC2E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC288 2025-07-15 22:00:02.379331*:B27DCC2F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC288 2025-07-15 22:00:02.488700*:B27DCCD1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029A46918 curCtx=0x000000004ED40E18 xsc->flg4=65792 flags=1 2025-07-15 22:00:02.488700*:B27DCCD2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029A46918 2025-07-15 22:00:03.079973 :B27DCDA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:03.080364 :B27DCDA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:03.080364*:B27DCDAC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC288 curCtx=0x0000000029A48510 xsc->flg4=65792 flags=2 2025-07-15 22:00:03.080364*:B27DCDAD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC288 2025-07-15 22:00:03.080610 :B27DCDB3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-15 22:00:03.081524 :B27DCDB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:03.085121 :B27DCDBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:03.085200 :B27DCDBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-15 22:00:03.085278 :B27DCDCC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-15 22:00:04.015544 :B27DCE72:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 17796, J001)