Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823140101\orcl_j002_20708_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:35288M/63366M, Ph+PgF:40461M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 20708, image: ORACLE.EXE (J002) *** 2025-08-23 14:01:01.335 *** SESSION ID:(233.32691) 2025-08-23 14:01:01.335 *** 2025-08-23 14:01:01.335 Process diagnostic dump for ORACLE.EXE (J002), OS id=20708, pid: 29, proc_ser: 213, sid: 233, sess_ser: 32691 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20708 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.054672 sec, exc=0.054672 sec, total=0.054672 sec wait times: max=0.500000 sec, heur=2.550999 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510108 sec, exc=0.510108 sec, total=0.510108 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486186 sec, exc=0.486186 sec, total=0.486186 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 233 serial 32691 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 14:00:58 - 14:01:01] idle wait at each sample [session created at: 14:00:58] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 14:01:01.335 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 20708, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 06:00:03.552462*:C372BAD0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x0000000059237918 pubTabIdxCnt=0 2025-08-23 06:00:03.552462*:C372BAD1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-08-23 06:00:03.552462*:C372BAD4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-08-23 06:00:03.552462*:C372BAD6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301FB6F0 estart=1755900001 eid=16777536 ctx=0x000000077B54EAC8 cwh=0x00000000301B0518 cpu=1265625 buffg=70269 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-23 06:00:03.552462*:C372BAD8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301FB6F0 curCtx=0x00000000591187D0 pubTabIdxCnt=0 2025-08-23 06:00:03.552462*:C372BAD9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301FB6F0 2025-08-23 06:00:03.552462*:C372BADA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301FB6F0 2025-08-23 06:00:03.552462*:C372BADD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x0000000059237918 xsc->flg4=65792 flags=1 2025-08-23 06:00:03.552462*:C372BADE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-08-23 06:00:03.739971*:C372BBC6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301FB6F0 curCtx=0x00000000591187D0 xsc->flg4=65792 flags=2 2025-08-23 06:00:03.739971*:C372BBC7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301FB6F0 2025-08-23 06:00:03.744418 :C372BBC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.764832 :C372BBEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.766785 :C372BBF2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 06:00:03.766978 :C372BBF3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 06:00:03.767808 :C372BBF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.767897 :C372BBF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.768743 :C372BC09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.770213 :C372BC11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.770503 :C372BC19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:03.833721*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-23 06:00:03.833721*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-23 06:00:03.833721*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-23 06:00:03.937707 :C372BC66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:05.864602 :C372BD5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:05.864617 :C372BD5B:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.016.0000d26b 2025-08-23 06:00:08.395688 :C372BED2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:08.397176 :C372BEDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:08.407467 :C372BEE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:08.436025 :C372BEEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:09.633873 :C372C009:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-08-23 06:00:09.644431 :C372C00A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:09.646069 :C372C012:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 06:00:11.066905 :C372C0F6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:11.053 2025-08-23 06:00:11.066907 :C372C0F7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11176 pso_num=29 pso_serial#=204 2025-08-23 06:00:11.067089 :C372C0F8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11176 (legacy spawn) 2025-08-23 06:00:11.075508 :C372C0F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:11.075697 :C372C0FE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 06:00:11.076469 :C372C103:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 06:00:11.085310 :C372C111:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:11.085 2025-08-23 06:00:11.085312 :C372C112:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20640 pso_num=29 pso_serial#=205 2025-08-23 06:00:11.085449 :C372C113:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20640 (legacy spawn) 2025-08-23 06:00:11.091911 :C372C114:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:11.092082 :C372C11C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 06:00:11.092878 :C372C11D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 06:00:26.569983 :C372C714:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:26.555 2025-08-23 06:00:26.569985 :C372C715:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19000 pso_num=29 pso_serial#=206 2025-08-23 06:00:26.570128 :C372C716:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19000 (legacy spawn) 2025-08-23 06:00:26.576575 :C372C717:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:26.576759 :C372C71C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 06:00:26.577603 :C372C721:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 06:00:26.586286 :C372C724:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:26.571 2025-08-23 06:00:26.586288 :C372C725:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22328 pso_num=29 pso_serial#=207 2025-08-23 06:00:26.586424 :C372C726:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22328 (legacy spawn) 2025-08-23 06:00:26.592985 :C372C727:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:26.593147 :C372C730:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 06:00:26.593962 :C372C731:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 06:00:29.766439 :C372C834:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:29.753 2025-08-23 06:00:29.766441 :C372C835:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21784 pso_num=29 pso_serial#=208 2025-08-23 06:00:29.766573 :C372C836:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21784 (legacy spawn) 2025-08-23 06:00:29.773162 :C372C84A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:29.773338 :C372C853:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 06:00:29.774162 :C372C854:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 06:00:29.782901 :C372C857:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:29.769 2025-08-23 06:00:29.782903 :C372C858:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17880 pso_num=29 pso_serial#=209 2025-08-23 06:00:29.783046 :C372C859:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17880 (legacy spawn) 2025-08-23 06:00:29.789621 :C372C85A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 06:00:29.789786 :C372C863:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 06:00:29.790608 :C372C864:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 08:11:26.870128 :C37D672F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 08:11:26.861 2025-08-23 08:11:26.870130 :C37D6730:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20888 pso_num=29 pso_serial#=210 2025-08-23 08:11:26.870268 :C37D6731:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20888 (legacy spawn) 2025-08-23 08:11:26.876730 :C37D673A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 08:11:26.876903 :C37D6743:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 08:11:26.877796 :C37D6744:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 10:00:25.796577 :C385FF93:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 10:00:25.796 2025-08-23 10:00:25.796578 :C385FF94:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21780 pso_num=29 pso_serial#=211 2025-08-23 10:00:25.796712 :C385FF95:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21780 osp_idx=27 osp_ver=16275 osp_pg=0 (spawn #517492) 2025-08-23 10:00:25.796713 :C385FF96:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 10:00:25.796714 :C385FF97:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=109 time=328927799 2025-08-23 10:00:25.796714 :C385FF98:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 10:00:25.796715 :C385FF99:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 10:00:25.796715 :C385FF9A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 10:00:25.796715 :C385FF9B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 10:00:25.796716 :C385FF9C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 10:00:25.797224 :C385FFA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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:36.737991 :C386092B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 10:01:06.845418 :C386137B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 11:53:48.629815 :C38ED5C1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 11:53:48.626 2025-08-23 11:53:48.629817 :C38ED5C2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19584 pso_num=29 pso_serial#=212 2025-08-23 11:53:48.629963 :C38ED5C3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19584 (legacy spawn) 2025-08-23 11:53:48.647100 :C38ED5D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 11:53:48.647264 :C38ED5DA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 11:53:48.648024 :C38ED5DB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-23 14:00:57.554829 :C398BD40:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 14:00:57.549 2025-08-23 14:00:57.554832 :C398BD41:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20708 pso_num=29 pso_serial#=213 2025-08-23 14:00:57.554988 :C398BD42:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20708 osp_idx=27 osp_ver=16277 osp_pg=0 (spawn #518048) 2025-08-23 14:00:57.554989 :C398BD43:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 14:00:57.554989 :C398BD44:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=110 time=343359565 2025-08-23 14:00:57.554989 :C398BD45:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 14:00:57.554990 :C398BD46:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 14:00:57.554991 :C398BD47:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 14:00:57.554991 :C398BD48:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 14:00:57.554992 :C398BD49:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 14:00:57.555547 :C398BD53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.701469 :C398BE6D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 14:00:58.701504*:C398BE6E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029A5BB58 estart=1755928858 eid=17020138 ctx=0x000000077BD98A08 cwh=0x00000000295E01B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-23 14:00:58.701504*:C398BE6F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029A5BB58 curCtx=0x00000000295EF708 pubTabIdxCnt=0 2025-08-23 14:00:58.701504*:C398BE70:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029A5BB58 2025-08-23 14:00:58.701504*:C398BE71:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029A5BB58 2025-08-23 14:00:58.701504*:C398BE72:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029A5BB58 curCtx=0x00000000295EF708 xsc->flg4=65792 flags=2 2025-08-23 14:00:58.701504*:C398BE73:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029A5BB58 2025-08-23 14:00:58.702139 :C398BE74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.717309 :C398BED9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.717391 :C398BEDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.718197 :C398BEE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.718579 :C398BEEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.718606 :C398BEEF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.726659 :C398BF05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.726736 :C398BF08:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-08-23 14:00:58.726776 :C398BF09:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-08-23 14:00:58.727136 :C398BF0F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:29:233] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-23 14:00:58.727561 :C398BF10:db_trace:kwqid.c@2146:kwqidrdq(): [10960:29:233] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-23 14:00:58.727762 :C398BF11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.728051 :C398BF12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.728086 :C398BF15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.729715 :C398BF1B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.729971 :C398BF1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.730003 :C398BF1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.732720 :C398BF25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.733531 :C398BF2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.735139 :C398BF35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.739336 :C398BF3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.742893 :C398BF45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.746964 :C398BF4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.750882 :C398BF55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.751487 :C398BF5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.756727 :C398BF65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.757003 :C398BF6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.761150 :C398BF75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.769187 :C398BF7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.769480 :C398BF85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.773177 :C398BF8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.775199 :C398BF95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.775366 :C398BF9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.775622 :C398BF9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.775645 :C398BFA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.775653 :C398BFA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.775786 :C398BFA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.775803 :C398BFAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.775809 :C398BFAC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.775905 :C398BFB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.775921 :C398BFB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.775927 :C398BFB6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.776029 :C398BFBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.776058 :C398BFBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.776068 :C398BFC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.776226 :C398BFC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.776254 :C398BFC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.776263 :C398BFCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.776360 :C398BFD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.776375 :C398BFD3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.776891 :C398BFD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.777012 :C398BFDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.777030 :C398BFDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.777310 :C398BFE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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 14:00:58.781377 :C398C008:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.781453 :C398C00B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-23 14:00:58.781733 :C398C011:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.781759 :C398C014:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-23 14:00:58.786704 :C398C01A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 14:00:58.786909 :C398C022:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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 29 (osid: 20708, J002)