Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622060009\orcl_j000_15152_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:36390M/63366M, Ph+PgF:42659M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15152, image: ORACLE.EXE (J000) *** 2025-06-22 06:00:10.088 *** SESSION ID:(193.27107) 2025-06-22 06:00:10.088 *** 2025-06-22 06:00:10.088 Process diagnostic dump for ORACLE.EXE (J000), OS id=15152, pid: 24, proc_ser: 146, sid: 193, sess_ser: 27107 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15152 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.281466 sec, exc=0.281466 sec, total=0.281466 sec wait times: max=0.500000 sec, heur=7.775288 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 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=12 seq_num=13 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.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.502098 sec, exc=0.502098 sec, total=0.502098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 Sampled Session History of session 193 serial 27107 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 06:00:02 - 06:00:10] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-22 06:00:10.088 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15152, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-22 05:57:29.806650 :A7FB1F1D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16732 pso_num=24 pso_serial#=128 2025-06-22 05:57:29.806785 :A7FB1F1E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16732 (legacy spawn) 2025-06-22 05:57:29.813229 :A7FB1F1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:29.813396 :A7FB1F28:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:57:29.814255 :A7FB1F29:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:57:29.822763 :A7FB1F2C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:57:29.814 2025-06-22 05:57:29.822765 :A7FB1F2D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13792 pso_num=24 pso_serial#=129 2025-06-22 05:57:29.822902 :A7FB1F2E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13792 (legacy spawn) 2025-06-22 05:57:29.829414 :A7FB1F2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:29.829581 :A7FB1F37:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:57:29.830415 :A7FB1F38:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:57:31.935503 :A7FB1FF8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:57:31.927 2025-06-22 05:57:31.935505 :A7FB1FF9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16428 pso_num=24 pso_serial#=130 2025-06-22 05:57:31.935641 :A7FB1FFA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16428 (legacy spawn) 2025-06-22 05:57:31.942244 :A7FB1FFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:31.942413 :A7FB2000:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:57:31.943284 :A7FB2005:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:57:33.096304 :A7FB209F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:57:33.083 2025-06-22 05:57:33.096306 :A7FB20A0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17072 pso_num=24 pso_serial#=131 2025-06-22 05:57:33.096462 :A7FB20A1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17072 (legacy spawn) 2025-06-22 05:57:33.116098 :A7FB20A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:33.116269 :A7FB20AB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:57:33.117104 :A7FB20AC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:57:33.125701 :A7FB20AF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:57:33.115 2025-06-22 05:57:33.125703 :A7FB20B0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17208 pso_num=24 pso_serial#=132 2025-06-22 05:57:33.125842 :A7FB20B1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17208 (legacy spawn) 2025-06-22 05:57:33.132280 :A7FB20B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:33.132451 :A7FB20BB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:57:33.133242 :A7FB20BC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:57:34.761139 :A7FB20F9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:57:34.756 2025-06-22 05:57:34.761141 :A7FB20FA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17488 pso_num=24 pso_serial#=133 2025-06-22 05:57:34.761316 :A7FB20FB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17488 osp_idx=22 osp_ver=440537 osp_pg=0 (spawn #310266) 2025-06-22 05:57:34.761318 :A7FB20FC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:57:34.761318 :A7FB20FD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=176 time=3547597954 2025-06-22 05:57:34.761318 :A7FB20FE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:57:34.761319 :A7FB20FF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:57:34.761319 :A7FB2100:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:57:34.761320 :A7FB2101:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:57:34.761320 :A7FB2102:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:57:34.761826 :A7FB2103:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-22 05:57:34.762472 :A7FB2118:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:34.769987 :A7FB2134:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-22 05:57:34.770355 :A7FB2135:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:34.770394 :A7FB2138:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-22 05:57:34.771298 :A7FB213E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:57:34.771499 :A7FB2143:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:58:04.871968 :A7FB2AF9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:58:06.052328 :A7FB2B90:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:58:06.043 2025-06-22 05:58:06.052330 :A7FB2B91:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16752 pso_num=24 pso_serial#=134 2025-06-22 05:58:06.052484 :A7FB2B92:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16752 (legacy spawn) 2025-06-22 05:58:06.059281 :A7FB2B93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:06.059469 :A7FB2B9C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:58:06.060393 :A7FB2B9D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:58:06.182004 :A7FB2BCF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:58:06.168 2025-06-22 05:58:06.182006 :A7FB2BD0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10700 pso_num=24 pso_serial#=135 2025-06-22 05:58:06.182159 :A7FB2BD1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10700 (legacy spawn) 2025-06-22 05:58:06.188718 :A7FB2BD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:06.188748 :A7FB2BD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:06.188895 :A7FB2BDC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:58:06.189765 :A7FB2BE9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:58:06.198222 :A7FB2BEC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:58:06.184 2025-06-22 05:58:06.198223 :A7FB2BED:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18196 pso_num=24 pso_serial#=136 2025-06-22 05:58:06.198365 :A7FB2BEE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18196 (legacy spawn) 2025-06-22 05:58:06.205081 :A7FB2BEF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:06.205251 :A7FB2BF8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:58:06.206102 :A7FB2BF9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:58:09.298322 :A7FB2CE4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:58:09.285 2025-06-22 05:58:09.298324 :A7FB2CE5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15412 pso_num=24 pso_serial#=137 2025-06-22 05:58:09.298469 :A7FB2CE6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15412 (legacy spawn) 2025-06-22 05:58:09.318293 :A7FB2CE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:09.318470 :A7FB2CF0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:58:09.319330 :A7FB2CF1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:58:09.327869 :A7FB2CF4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:58:09.316 2025-06-22 05:58:09.327871 :A7FB2CF5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15648 pso_num=24 pso_serial#=138 2025-06-22 05:58:09.328002 :A7FB2CF6:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15648 (legacy spawn) 2025-06-22 05:58:09.334456 :A7FB2CF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:09.334615 :A7FB2CFF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:58:09.335392 :A7FB2D01:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:58:34.833630 :A7FB347D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:58:34.828 2025-06-22 05:58:34.833633 :A7FB347E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14216 pso_num=24 pso_serial#=139 2025-06-22 05:58:34.833769 :A7FB347F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14216 osp_idx=22 osp_ver=440543 osp_pg=0 (spawn #310269) 2025-06-22 05:58:34.833770 :A7FB3480:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:58:34.833770 :A7FB3481:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=177 time=3547658016 2025-06-22 05:58:34.833770 :A7FB3482:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:58:34.833771 :A7FB3483:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:58:34.833771 :A7FB3484:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:58:34.833771 :A7FB3485:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:58:34.833772 :A7FB3486:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:58:34.834307 :A7FB3487:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-22 05:58:34.834952 :A7FB349C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:34.845930 :A7FB34B8:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-22 05:58:34.846460 :A7FB34B9:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-22 05:58:34.848758 :A7FB34BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-22 05:58:34.849070 :A7FB34BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:34.849105 :A7FB34BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-22 05:58:34.849976 :A7FB34C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:58:34.850100 :A7FB34CD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:04.939356 :A7FB3E84:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:14.339959 :A7FB4161:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:14.330 2025-06-22 05:59:14.339961 :A7FB4162:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17804 pso_num=24 pso_serial#=140 2025-06-22 05:59:14.340102 :A7FB4163:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17804 (legacy spawn) 2025-06-22 05:59:14.346545 :A7FB4164:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:14.346713 :A7FB4169:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:14.347497 :A7FB416E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:18.830538 :A7FB42F9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:18.823 2025-06-22 05:59:18.830541 :A7FB42FA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16084 pso_num=24 pso_serial#=141 2025-06-22 05:59:18.830679 :A7FB42FB:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16084 (legacy spawn) 2025-06-22 05:59:18.837154 :A7FB42FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:18.837329 :A7FB4302:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:18.838129 :A7FB4306:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:18.846904 :A7FB4309:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:18.838 2025-06-22 05:59:18.846906 :A7FB430A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3236 pso_num=24 pso_serial#=142 2025-06-22 05:59:18.847047 :A7FB430B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3236 (legacy spawn) 2025-06-22 05:59:18.853474 :A7FB430C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:18.853644 :A7FB4315:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:18.854460 :A7FB4316:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:21.902629 :A7FB4407:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:21.901 2025-06-22 05:59:21.902632 :A7FB4408:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14096 pso_num=24 pso_serial#=143 2025-06-22 05:59:21.902774 :A7FB4409:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14096 (legacy spawn) 2025-06-22 05:59:21.909160 :A7FB440A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:21.909333 :A7FB440F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:21.910114 :A7FB4414:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:21.918844 :A7FB4417:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:21.917 2025-06-22 05:59:21.918846 :A7FB4418:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13028 pso_num=24 pso_serial#=144 2025-06-22 05:59:21.918984 :A7FB4419:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13028 (legacy spawn) 2025-06-22 05:59:21.925414 :A7FB441A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:21.925573 :A7FB4423:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:21.926358 :A7FB4424:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:27.776719 :A7FB462A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:27.769 2025-06-22 05:59:27.776721 :A7FB462B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16688 pso_num=24 pso_serial#=145 2025-06-22 05:59:27.776862 :A7FB462C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16688 (legacy spawn) 2025-06-22 05:59:27.783323 :A7FB462D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:27.783514 :A7FB4634:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 05:59:27.784356 :A7FB4637:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-22 05:59:34.918592 :A7FB4837:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-22 05:59:34.913 2025-06-22 05:59:34.918594 :A7FB4838:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15152 pso_num=24 pso_serial#=146 2025-06-22 05:59:34.918729 :A7FB4839:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15152 osp_idx=22 osp_ver=440550 osp_pg=0 (spawn #310271) 2025-06-22 05:59:34.918730 :A7FB483A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 05:59:34.918730 :A7FB483B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=178 time=3547718110 2025-06-22 05:59:34.918731 :A7FB483C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 05:59:34.918731 :A7FB483D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 05:59:34.918731 :A7FB483E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 05:59:34.918732 :A7FB483F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 05:59:34.918732 :A7FB4840:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 05:59:34.919221 :A7FB4841:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-22 05:59:34.919883 :A7FB4856:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:34.927493 :A7FB4872:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-22 05:59:34.927862 :A7FB4873:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:34.927898 :A7FB4876:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-22 05:59:34.928733 :A7FB487C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 05:59:34.928858 :A7FB4885:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 06:00:02.178967 :A7FB5384:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-22 06:00:02.178967*:A7FB5396:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1750543201 eid=16782789 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-22 06:00:02.178967*:A7FB5397:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-06-22 06:00:02.178967*:A7FB5398:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-06-22 06:00:02.178967*:A7FB5399:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-06-22 06:00:02.178967*:A7FB539A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-06-22 06:00:02.178967*:A7FB539B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-06-22 06:00:02.184784 :A7FB53AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.184839 :A7FB53AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-22 06:00:02.196164 :A7FB5524:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.201496 :A7FB556A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.202281 :A7FB5587:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.208234 :A7FB55DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.208388 :A7FB55F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.209096 :A7FB561A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.209296 :A7FB562A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-22 06:00:02.210308 :A7FB565E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] 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-06-22 06:00:02.210732 :A7FB5678:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] 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-06-22 06:00:02.210774 :A7FB567A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.210811 :A7FB5681:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.210877 :A7FB5690:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-22 06:00:02.303855 :A7FB56B2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-22 06:00:02.305954 :A7FB56B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.305995 :A7FB56B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-22 06:00:02.308442 :A7FB56BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-22 06:00:02.311721 :A7FB56BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.311805 :A7FB56C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-22 06:00:02.311890 :A7FB56CF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 15152, J000)