Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823100031\orcl_j001_20688_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:35293M/63366M, Ph+PgF:40464M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 20688, image: ORACLE.EXE (J001) *** 2025-08-23 10:00:32.086 *** SESSION ID:(225.45955) 2025-08-23 10:00:32.086 *** 2025-08-23 10:00:32.086 Process diagnostic dump for ORACLE.EXE (J001), OS id=20688, pid: 28, proc_ser: 170, sid: 225, sess_ser: 45955 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20688 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.129221 sec, exc=0.129221 sec, total=0.129221 sec wait times: max=0.500000 sec, heur=4.642386 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=8 seq_num=9 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.514238 sec, exc=0.514238 sec, total=0.514238 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000009 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.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.498871 sec, exc=0.498871 sec, total=0.498871 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 225 serial 45955 --------------------------------------------------- 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, 10:00:27 - 10:00:32] idle wait at each sample [session created at: 10:00:27] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 10:00:32.086 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 20688, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 09:58:07.402648 :C385D0A6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:13.185143 :C385D24A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:13.177 2025-08-23 09:58:13.185145 :C385D24B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19936 pso_num=28 pso_serial#=155 2025-08-23 09:58:13.185281 :C385D24C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19936 (legacy spawn) 2025-08-23 09:58:13.191849 :C385D24D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:13.192021 :C385D252:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:13.192909 :C385D257:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:16.453416 :C385D37A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:16.444 2025-08-23 09:58:16.453418 :C385D37B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21932 pso_num=28 pso_serial#=156 2025-08-23 09:58:16.453561 :C385D37C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21932 (legacy spawn) 2025-08-23 09:58:16.460004 :C385D37D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:16.460180 :C385D382:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:16.460979 :C385D387:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:40.604951 :C385DB6A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:40.590 2025-08-23 09:58:40.604953 :C385DB6B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21172 pso_num=28 pso_serial#=157 2025-08-23 09:58:40.605090 :C385DB6C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21172 (legacy spawn) 2025-08-23 09:58:40.611611 :C385DB6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:40.611779 :C385DB72:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:40.612585 :C385DB77:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:40.630559 :C385DB7A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:40.622 2025-08-23 09:58:40.630561 :C385DB7B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21600 pso_num=28 pso_serial#=158 2025-08-23 09:58:40.630702 :C385DB7C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21600 (legacy spawn) 2025-08-23 09:58:40.637190 :C385DB7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:40.637411 :C385DB86:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:40.638230 :C385DB87:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:43.686774 :C385DC82:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:43.673 2025-08-23 09:58:43.686776 :C385DC83:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22052 pso_num=28 pso_serial#=159 2025-08-23 09:58:43.686917 :C385DC84:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22052 (legacy spawn) 2025-08-23 09:58:43.693357 :C385DC85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:43.693554 :C385DC8A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:43.694354 :C385DC8F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:43.703087 :C385DC92:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:43.689 2025-08-23 09:58:43.703089 :C385DC93:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22016 pso_num=28 pso_serial#=160 2025-08-23 09:58:43.703228 :C385DC94:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22016 (legacy spawn) 2025-08-23 09:58:43.709708 :C385DC95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:43.709879 :C385DC9E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:43.710689 :C385DC9F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:44.260097 :C385DCC0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:44.251 2025-08-23 09:58:44.260099 :C385DCC1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22216 pso_num=28 pso_serial#=161 2025-08-23 09:58:44.260234 :C385DCC2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22216 (legacy spawn) 2025-08-23 09:58:44.266700 :C385DCC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:44.266869 :C385DCCC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:44.267769 :C385DCF9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:58:50.548460 :C385DEE5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:58:50.539 2025-08-23 09:58:50.548462 :C385DEE6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22384 pso_num=28 pso_serial#=162 2025-08-23 09:58:50.548652 :C385DEE7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22384 (legacy spawn) 2025-08-23 09:58:50.555082 :C385DEE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:58:50.555241 :C385DEF1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:58:50.556080 :C385DEF2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:59:46.476903 :C385F082:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:59:46.463 2025-08-23 09:59:46.476905 :C385F083:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22468 pso_num=28 pso_serial#=163 2025-08-23 09:59:46.477053 :C385F084:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22468 (legacy spawn) 2025-08-23 09:59:46.483837 :C385F085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:46.484018 :C385F08A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:59:46.484949 :C385F08F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:59:53.446135 :C385F307:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:59:53.431 2025-08-23 09:59:53.446137 :C385F308:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21908 pso_num=28 pso_serial#=164 2025-08-23 09:59:53.446291 :C385F309:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21908 (legacy spawn) 2025-08-23 09:59:53.452795 :C385F30A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:53.452984 :C385F313:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:59:53.453887 :C385F314:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:59:53.462751 :C385F317:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:59:53.462 2025-08-23 09:59:53.462753 :C385F318:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2696 pso_num=28 pso_serial#=165 2025-08-23 09:59:53.462889 :C385F319:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2696 (legacy spawn) 2025-08-23 09:59:53.479547 :C385F31A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:53.479724 :C385F323:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:59:53.480613 :C385F324:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:59:56.579679 :C385F40C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:59:56.566 2025-08-23 09:59:56.579681 :C385F40D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20448 pso_num=28 pso_serial#=166 2025-08-23 09:59:56.579821 :C385F40E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20448 (legacy spawn) 2025-08-23 09:59:56.586270 :C385F40F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:56.586444 :C385F418:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:59:56.587237 :C385F419:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:59:56.595955 :C385F41C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:59:56.582 2025-08-23 09:59:56.595957 :C385F41D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20520 pso_num=28 pso_serial#=167 2025-08-23 09:59:56.596102 :C385F41E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20520 (legacy spawn) 2025-08-23 09:59:56.602664 :C385F41F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:56.602830 :C385F428:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:59:56.603625 :C385F429:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 09:59:58.845963 :C385F4D7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 09:59:58.838 2025-08-23 09:59:58.845965 :C385F4D8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22300 pso_num=28 pso_serial#=168 2025-08-23 09:59:58.846100 :C385F4D9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22300 (legacy spawn) 2025-08-23 09:59:58.852653 :C385F4DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:58.852686 :C385F4DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 09:59:58.852854 :C385F4E0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 09:59:58.853757 :C385F4F1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 10:00:25.626623 :C385FDA1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 10:00:25.624 2025-08-23 10:00:25.626625 :C385FDA2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8628 pso_num=28 pso_serial#=169 2025-08-23 10:00:25.626764 :C385FDA3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=8628 osp_idx=26 osp_ver=252157 osp_pg=0 (spawn #517488) 2025-08-23 10:00:25.626765 :C385FDA4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 10:00:25.626765 :C385FDA5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=188 time=328927627 2025-08-23 10:00:25.626765 :C385FDA6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 10:00:25.626766 :C385FDA7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 10:00:25.626766 :C385FDA8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 10:00:25.626767 :C385FDA9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 10:00:25.626767 :C385FDAA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 10:00:25.627259 :C385FDAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.627303 :C385FDB2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 10:00:25.627373 :C385FDBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.631299 :C385FDC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.689590 :C385FE12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.690802 :C385FE1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.693345 :C385FE22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.694124 :C385FE2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.694146 :C385FE2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.694185 :C385FE38:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 10:00:25.695156 :C385FE43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.695174 :C385FE46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.695200 :C385FE51:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 10:00:25.695628 :C385FE5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.698016 :C385FE64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.702734 :C385FE6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.703044 :C385FE76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.708521 :C385FE86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.713164 :C385FE96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.714325 :C385FEA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.714333 :C385FEA7:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x000a.01e.001793f7 2025-08-23 10:00:25.717304 :C385FEB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.717476 :C385FEC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.717764 :C385FECD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.717819 :C385FED0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-08-23 10:00:25.717836 :C385FED1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-08-23 10:00:25.720019 :C385FED7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.720066 :C385FEDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-08-23 10:00:25.720081 :C385FEDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-08-23 10:00:25.720254 :C385FEE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.720272 :C385FEE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.720336 :C385FEF4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 10:00:25.722694 :C385FEFA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 10:00:25.790958 :C385FF46:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 10:00:25.780 2025-08-23 10:00:25.790960 :C385FF47:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20688 pso_num=28 pso_serial#=170 2025-08-23 10:00:25.791106 :C385FF48:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20688 osp_idx=26 osp_ver=252158 osp_pg=0 (spawn #517491) 2025-08-23 10:00:25.791107 :C385FF49:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 10:00:25.791108 :C385FF4A:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=189 time=328927799 2025-08-23 10:00:25.791109 :C385FF4B:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 10:00:25.791109 :C385FF4C:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 10:00:25.791109 :C385FF4D:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 10:00:25.791110 :C385FF4E:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 10:00:25.791110 :C385FF4F:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 10:00:25.791646 :C385FF59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.796717 :C385FF9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.796756 :C385FFA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-08-23 10:00:25.801895 :C385FFB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:25.940564 :C38601C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:26.076843 :C38601D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:26.210890 :C38601E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.344877 :C3860308:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.345906 :C3860310:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.351426 :C3860318:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.366383 :C3860320:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.367077 :C3860328:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.367649 :C3860330:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.368193 :C3860338:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.370914 :C3860340:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.371814 :C3860348:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-23 10:00:27.372575 :C3860349:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-23 10:00:27.373338 :C386034A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-23 10:00:27.373825 :C386034B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-23 10:00:27.373832 :C386034C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-23 10:00:27.374447 :C386034D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-23 10:00:27.374970 :C386034E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-23 10:00:27.375279 :C386034F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-23 10:00:27.376265 :C3860350:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-23 10:00:27.416200 :C3860351:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.416247 :C3860354:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-23 10:00:27.417188 :C386035A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.418065 :C3860362:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-23 10:00:27.418587 :C3860363:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.438334 :C386036B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.438385 :C386036E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-23 10:00:27.439203 :C3860374:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.439649 :C386037C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.440069 :C3860384:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.440263 :C386038C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.440404 :C3860394:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.440544 :C386039C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-08-23 10:00:27.444672 :C386039D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.444754 :C38603A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-23 10:00:27.444852 :C38603AD:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 20688, J001)