Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629060008\orcl_j000_16116_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:36532M/63366M, Ph+PgF:42598M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 16116, image: ORACLE.EXE (J000) *** 2025-06-29 06:00:08.816 *** SESSION ID:(193.58185) 2025-06-29 06:00:08.816 *** 2025-06-29 06:00:08.816 Process diagnostic dump for ORACLE.EXE (J000), OS id=16116, pid: 24, proc_ser: 57, sid: 193, sess_ser: 58185 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16116 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.142463 sec, exc=0.142463 sec, total=0.142463 sec wait times: max=0.500000 sec, heur=6.661185 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500155 sec, exc=0.500155 sec, total=0.500155 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=11 seq_num=12 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.507114 sec, exc=0.507114 sec, total=0.507114 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500036 sec, exc=0.500036 sec, total=0.500036 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500135 sec, exc=0.500135 sec, total=0.500135 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=7 seq_num=8 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500147 sec, exc=0.500147 sec, total=0.500147 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499967 sec, exc=0.499967 sec, total=0.499967 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500022 sec, exc=0.500022 sec, total=0.500022 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 193 serial 58185 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 06:00:02 - 06:00:08] 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-29 06:00:08.816 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 16116, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 05:56:10.917669 :AB16B683:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:56:10.907 2025-06-29 05:56:10.917671 :AB16B684:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14720 pso_num=24 pso_serial#=43 2025-06-29 05:56:10.917806 :AB16B685:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14720 (legacy spawn) 2025-06-29 05:56:10.924259 :AB16B686: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-29 05:56:10.924412 :AB16B68F: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-29 05:56:10.925180 :AB16B690:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:56:13.997957 :AB16B781:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:56:13.985 2025-06-29 05:56:13.997959 :AB16B782:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10376 pso_num=24 pso_serial#=44 2025-06-29 05:56:13.998099 :AB16B783:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10376 (legacy spawn) 2025-06-29 05:56:14.004615 :AB16B784: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-29 05:56:14.004781 :AB16B789: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-29 05:56:14.005577 :AB16B78E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:56:14.014201 :AB16B791:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:56:14.001 2025-06-29 05:56:14.014203 :AB16B792:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18352 pso_num=24 pso_serial#=45 2025-06-29 05:56:14.014341 :AB16B793:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18352 (legacy spawn) 2025-06-29 05:56:14.020866 :AB16B794: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-29 05:56:14.021035 :AB16B79D: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-29 05:56:14.021909 :AB16B79E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:56:33.841372 :AB16BE0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:56:33.835 2025-06-29 05:56:33.841374 :AB16BE0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4144 pso_num=24 pso_serial#=46 2025-06-29 05:56:33.841506 :AB16BE10:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4144 osp_idx=22 osp_ver=481496 osp_pg=0 (spawn #333609) 2025-06-29 05:56:33.841506 :AB16BE11: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-29 05:56:33.841507 :AB16BE12:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=252 time=4152337032 2025-06-29 05:56:33.841507 :AB16BE13:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:56:33.841508 :AB16BE14:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:56:33.841508 :AB16BE15:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:56:33.841508 :AB16BE16:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:56:33.841509 :AB16BE17:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:56:33.841976 :AB16BE18: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-29 05:56:33.842621 :AB16BE2D: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-29 05:56:33.853664 :AB16BE49:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 05:56:33.854123 :AB16BE4A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 05:56:33.856491 :AB16BE4B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 05:56:33.856836 :AB16BE4C: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-29 05:56:33.856873 :AB16BE4F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 05:56:33.857747 :AB16BE55: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-29 05:56:33.857870 :AB16BE5E: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-29 05:57:03.911524 :AB16C7C8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:05.560031 :AB16C81C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:05.552 2025-06-29 05:57:05.560033 :AB16C81D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16396 pso_num=24 pso_serial#=47 2025-06-29 05:57:05.560171 :AB16C81E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16396 (legacy spawn) 2025-06-29 05:57:05.566754 :AB16C81F: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-29 05:57:05.566940 :AB16C824: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-29 05:57:05.567763 :AB16C829:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:11.961123 :AB16CA55:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:11.952 2025-06-29 05:57:11.961125 :AB16CA56:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=316 pso_num=24 pso_serial#=48 2025-06-29 05:57:11.961259 :AB16CA57:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=316 (legacy spawn) 2025-06-29 05:57:11.967632 :AB16CA58: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-29 05:57:11.967808 :AB16CA5D: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-29 05:57:11.968608 :AB16CA62:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:23.589388 :AB16CDC1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:23.586 2025-06-29 05:57:23.589391 :AB16CDC2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10676 pso_num=24 pso_serial#=49 2025-06-29 05:57:23.589532 :AB16CDC3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10676 (legacy spawn) 2025-06-29 05:57:23.595908 :AB16CDC4: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-29 05:57:23.596082 :AB16CDC9: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-29 05:57:23.596884 :AB16CDCE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:23.605557 :AB16CDD1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:23.601 2025-06-29 05:57:23.605559 :AB16CDD2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18348 pso_num=24 pso_serial#=50 2025-06-29 05:57:23.605699 :AB16CDD3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18348 (legacy spawn) 2025-06-29 05:57:23.612144 :AB16CDD4: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-29 05:57:23.612303 :AB16CDDD: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-29 05:57:23.613129 :AB16CDDE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:26.898027 :AB16CF03:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:26.884 2025-06-29 05:57:26.898029 :AB16CF04:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17152 pso_num=24 pso_serial#=51 2025-06-29 05:57:26.898161 :AB16CF05:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17152 (legacy spawn) 2025-06-29 05:57:26.904599 :AB16CF06: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-29 05:57:26.904781 :AB16CF0B: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-29 05:57:26.905560 :AB16CF10:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:26.924570 :AB16CF13:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:26.916 2025-06-29 05:57:26.924572 :AB16CF14:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17304 pso_num=24 pso_serial#=52 2025-06-29 05:57:26.924706 :AB16CF15:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17304 (legacy spawn) 2025-06-29 05:57:26.931104 :AB16CF16: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-29 05:57:26.931271 :AB16CF1F: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-29 05:57:26.932081 :AB16CF20:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:57:33.921130 :AB16D17B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:57:33.915 2025-06-29 05:57:33.921131 :AB16D17C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17020 pso_num=24 pso_serial#=53 2025-06-29 05:57:33.921266 :AB16D17D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17020 osp_idx=22 osp_ver=481503 osp_pg=0 (spawn #333611) 2025-06-29 05:57:33.921267 :AB16D17E: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-29 05:57:33.921267 :AB16D17F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=253 time=4152397110 2025-06-29 05:57:33.921268 :AB16D180:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:57:33.921268 :AB16D181:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:57:33.921269 :AB16D182:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:57:33.921269 :AB16D183:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:57:33.921269 :AB16D184:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:57:33.921769 :AB16D185: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-29 05:57:33.922399 :AB16D19A: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-29 05:57:33.929871 :AB16D1B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 05:57:33.930242 :AB16D1B7: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-29 05:57:33.930278 :AB16D1BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 05:57:33.931114 :AB16D1C0: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-29 05:57:33.931232 :AB16D1C9: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-29 05:58:04.048280 :AB16DBE0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:58:07.748174 :AB16DCE0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:58:07.740 2025-06-29 05:58:07.748176 :AB16DCE1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7460 pso_num=24 pso_serial#=54 2025-06-29 05:58:07.748317 :AB16DCE2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7460 (legacy spawn) 2025-06-29 05:58:07.754855 :AB16DCE3: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-29 05:58:07.755049 :AB16DCE8: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-29 05:58:07.755897 :AB16DCED:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:58:20.231645 :AB16E09C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:58:20.223 2025-06-29 05:58:20.231647 :AB16E09D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17512 pso_num=24 pso_serial#=55 2025-06-29 05:58:20.231785 :AB16E09E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17512 (legacy spawn) 2025-06-29 05:58:20.238190 :AB16E09F: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-29 05:58:20.238371 :AB16E0A4: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-29 05:58:20.239185 :AB16E0A9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:58:34.034592 :AB16E538:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:58:34.028 2025-06-29 05:58:34.034594 :AB16E539:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12532 pso_num=24 pso_serial#=56 2025-06-29 05:58:34.034737 :AB16E53A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12532 osp_idx=22 osp_ver=481506 osp_pg=0 (spawn #333613) 2025-06-29 05:58:34.034738 :AB16E53B: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-29 05:58:34.034738 :AB16E53C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=254 time=4152457219 2025-06-29 05:58:34.034739 :AB16E53D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:58:34.034739 :AB16E53E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:58:34.034739 :AB16E53F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:58:34.034740 :AB16E540:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:58:34.034740 :AB16E541:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:58:34.035242 :AB16E542: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-29 05:58:34.035900 :AB16E557: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-29 05:58:34.046927 :AB16E573:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 05:58:34.047406 :AB16E574:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 05:58:34.049747 :AB16E575:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 05:58:34.050129 :AB16E576: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-29 05:58:34.050165 :AB16E579:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 05:58:34.051077 :AB16E57F: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-29 05:58:34.051205 :AB16E588: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-29 05:58:59.079804 :AB16ED46: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-29 05:58:59.079826*:AB16ED48:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1751147938 eid=16932344 ctx=0x000000077BD98A08 cwh=0x00000000232802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 05:58:59.079826*:AB16ED49:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 pubTabIdxCnt=0 2025-06-29 05:58:59.079826*:AB16ED4A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-06-29 05:58:59.079826*:AB16ED4B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-06-29 05:58:59.079826*:AB16ED4C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 xsc->flg4=65792 flags=2 2025-06-29 05:58:59.079826*:AB16ED4D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-06-29 05:58:59.080429 :AB16ED4E: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-29 05:58:59.085766 :AB16ED56: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-29 05:58:59.086556 :AB16ED5E: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-29 05:58:59.086684 :AB16ED67: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-29 05:59:29.179741 :AB16F789:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-29 05:59:34.128311 :AB16F94E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-29 05:59:34.123 2025-06-29 05:59:34.128313 :AB16F94F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16116 pso_num=24 pso_serial#=57 2025-06-29 05:59:34.128452 :AB16F950:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16116 osp_idx=22 osp_ver=481507 osp_pg=0 (spawn #333617) 2025-06-29 05:59:34.128453 :AB16F951: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-29 05:59:34.128453 :AB16F952:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=255 time=4152517313 2025-06-29 05:59:34.128454 :AB16F953:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:59:34.128454 :AB16F954:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:59:34.128454 :AB16F955:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:59:34.128455 :AB16F956:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:59:34.128455 :AB16F957:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:59:34.128972 :AB16F958: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-29 05:59:34.129593 :AB16F96D: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-29 05:59:34.137135 :AB16F989:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-29 05:59:34.137506 :AB16F98A: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-29 05:59:34.137546 :AB16F98D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-29 05:59:34.138400 :AB16F993: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-29 05:59:34.138510 :AB16F99C: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-29 06:00:02.021993 :AB170422: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-29 06:00:02.021993*:AB170438:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1751148001 eid=16783219 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 06:00:02.021993*:AB170439:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-06-29 06:00:02.021993*:AB17043A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-06-29 06:00:02.021993*:AB17043B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-06-29 06:00:02.021993*:AB17043C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-06-29 06:00:02.021993*:AB17043D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-06-29 06:00:02.027698 :AB17044C: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-29 06:00:02.027745 :AB170453:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-29 06:00:02.038862 :AB1705C1: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-29 06:00:02.043970 :AB170609: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-29 06:00:02.044350 :AB170612:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=30 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-29 06:00:02.050716 :AB17067F: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-29 06:00:02.050879 :AB170691: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-29 06:00:02.051141 :AB1706A9: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-29 06:00:02.051522 :AB1706C0: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-29 06:00:02.051706 :AB1706D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-29 06:00:02.053147 :AB170719: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-29 06:00:02.053181 :AB17071A: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-29 06:00:02.053267 :AB17072D: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-29 06:00:02.146905 :AB170763: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-29 06:00:02.149143 :AB170765: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-29 06:00:02.149184 :AB170768:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-29 06:00:02.151741 :AB17076E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-29 06:00:02.155267 :AB17076F: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-29 06:00:02.155350 :AB170772: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-29 06:00:02.155431 :AB17077F: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: 16116, J000)