Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621140608\orcl_m001_17288_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:36400M/63366M, Ph+PgF:42789M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 17288, image: ORACLE.EXE (M001) *** 2025-06-21 14:06:08.150 *** 2025-06-21 14:06:08.150 Process diagnostic dump for ORACLE.EXE (M001), OS id=17288, pid: 29, proc_ser: 162, sid: ------------------------------------------------------------------------------- Skipping session state dump because target process has no session. ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-21 14:06:08.150 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 17288, M001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-21 06:00:02.075779 :A78AA629: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-06-21 06:00:02.075934 :A78AA631: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-06-21 06:00:02.076093 :A78AA639: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-06-21 06:00:02.076275 :A78AA641: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-06-21 06:00:02.076451 :A78AA649: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-06-21 06:00:02.076602 :A78AA651: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-06-21 06:00:02.076754 :A78AA659: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-06-21 06:00:02.076901 :A78AA661: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-06-21 06:00:02.077053 :A78AA669: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-06-21 06:00:02.077200 :A78AA671: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-06-21 06:00:02.077344 :A78AA679: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-06-21 06:00:02.077501 :A78AA681: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-06-21 06:00:02.077664 :A78AA689: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-06-21 06:00:02.077810 :A78AA691: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-06-21 06:00:02.077963 :A78AA699: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-06-21 06:00:02.078115 :A78AA6A1: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-06-21 06:00:02.078259 :A78AA6A9: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-06-21 06:00:02.078404 :A78AA6B1: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-06-21 06:00:02.078554 :A78AA6B9: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-06-21 06:00:02.078700 :A78AA6C1: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-06-21 06:00:02.078842 :A78AA6C9: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-06-21 06:00:02.098480 :A78AA6D1: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-06-21 06:00:03.765711 :A78AAA73: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-06-21 06:00:03.786454 :A78AAAAD: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-06-21 06:00:03.788565 :A78AAAC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-21 06:00:03.788764 :A78AAAC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-21 06:00:03.790927 :A78AAAC6: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-06-21 06:00:03.791035 :A78AAAC9: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-06-21 06:00:03.792001 :A78AAADB: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-06-21 06:00:03.793515 :A78AAAE3: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-06-21 06:00:03.793790 :A78AAAEB: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-06-21 06:00:03.856878*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-21 06:00:03.856878*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-21 06:00:03.856878*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-21 06:00:03.872499*:A78AAB03:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000004665E440 estart=1750456802 eid=16777277 ctx=0x000000077DDC2AB8 cwh=0x0000000046581058 cpu=15625 buffg=49 interb=0 r=0 rb=0 w=0 wb=0 2025-06-21 06:00:03.872499*:A78AAB04:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000004665E440 curCtx=0x0000000046AAFE88 pubTabIdxCnt=0 2025-06-21 06:00:03.872499*:A78AAB05:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000004665E440 2025-06-21 06:00:03.872499*:A78AAB06:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000004665E440 2025-06-21 06:00:03.888134*:A78AAB11:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000004665E440 curCtx=0x0000000046AAFE88 xsc->flg4=65792 flags=1 2025-06-21 06:00:03.888134*:A78AAB12:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000004665E440 2025-06-21 06:00:03.888134*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-21 06:00:03.888134*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-21 06:00:03.888134*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-21 06:00:03.888134*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-21 06:00:03.952176 :A78AAB32: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-06-21 06:00:05.864840 :A78AAC32: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-06-21 06:00:05.864851 :A78AAC33:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.016.0001ce4c 2025-06-21 06:00:08.409135 :A78AADB6: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-06-21 06:00:08.410532 :A78AADBE: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-06-21 06:00:08.421075 :A78AADC6: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-06-21 06:00:08.446084 :A78AADCE: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-06-21 06:00:09.580922 :A78AAECB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-06-21 06:00:09.591485 :A78AAED0: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-06-21 06:00:09.592973 :A78AAED8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 06:00:12.580321 :A78AAFCF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 06:00:12.572 2025-06-21 06:00:12.580323 :A78AAFD0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9008 pso_num=29 pso_serial#=152 2025-06-21 06:00:12.580463 :A78AAFD1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9008 (legacy spawn) 2025-06-21 06:00:12.587730 :A78AAFD2: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-06-21 06:00:12.587895 :A78AAFD7: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-06-21 06:00:12.588688 :A78AAFDC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 06:00:30.189489 :A78AB748:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 06:00:30.175 2025-06-21 06:00:30.189490 :A78AB749:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16224 pso_num=29 pso_serial#=153 2025-06-21 06:00:30.189628 :A78AB74A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16224 (legacy spawn) 2025-06-21 06:00:30.196073 :A78AB74B: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-06-21 06:00:30.196248 :A78AB750: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-06-21 06:00:30.197094 :A78AB755:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 06:00:30.205790 :A78AB758:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 06:00:30.190 2025-06-21 06:00:30.205792 :A78AB759:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18284 pso_num=29 pso_serial#=154 2025-06-21 06:00:30.205926 :A78AB75A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18284 (legacy spawn) 2025-06-21 06:00:30.212328 :A78AB75B: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-06-21 06:00:30.212497 :A78AB764: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-06-21 06:00:30.213281 :A78AB765:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:29:00.712563 :A796AF3D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:29:00.703 2025-06-21 08:29:00.712565 :A796AF3E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17396 pso_num=29 pso_serial#=155 2025-06-21 08:29:00.712731 :A796AF3F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17396 osp_idx=27 osp_ver=13159 osp_pg=0 (spawn #307278) 2025-06-21 08:29:00.712732 :A796AF40:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:29:00.712732 :A796AF41:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=93 time=3470283891 2025-06-21 08:29:00.712733 :A796AF42:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:29:00.712733 :A796AF43:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:29:00.712734 :A796AF44:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:29:00.712734 :A796AF45:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:29:00.712735 :A796AF46:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:29:00.713562 :A796AF47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:29:00.713605 :A796AF4D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 08:29:00.814212 :A796AF7F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:29:01.946640 :A796AFC7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:29:01.932 2025-06-21 08:29:01.946642 :A796AFC8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16940 pso_num=29 pso_serial#=156 2025-06-21 08:29:01.946775 :A796AFC9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16940 (legacy spawn) 2025-06-21 08:29:01.953440 :A796AFCA: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-06-21 08:29:01.953744 :A796AFD3: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-06-21 08:29:01.954525 :A796AFD4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:29:01.962843 :A796AFD7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:29:01.948 2025-06-21 08:29:01.962845 :A796AFD8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15500 pso_num=29 pso_serial#=157 2025-06-21 08:29:01.962975 :A796AFD9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15500 (legacy spawn) 2025-06-21 08:29:01.969456 :A796AFDA: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-06-21 08:29:01.969619 :A796AFE3: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-06-21 08:29:01.970453 :A796AFE4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:29:03.003535 :A796B07A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:29:02.995 2025-06-21 08:29:03.003537 :A796B07B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17724 pso_num=29 pso_serial#=158 2025-06-21 08:29:03.003670 :A796B07C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17724 osp_idx=27 osp_ver=13162 osp_pg=0 (spawn #307280) 2025-06-21 08:29:03.003671 :A796B07D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 08:29:03.003671 :A796B07E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=94 time=3470286188 2025-06-21 08:29:03.003671 :A796B07F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 08:29:03.003672 :A796B080:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 08:29:03.003672 :A796B081:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 08:29:03.003672 :A796B082:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 08:29:03.003673 :A796B083:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 08:29:03.004236 :A796B084:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 08:29:03.004282 :A796B08A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 08:29:04.372572 :A796B571:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:29:05.097186 :A796B694:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:29:05.099 2025-06-21 08:29:05.097188 :A796B695:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16624 pso_num=29 pso_serial#=159 2025-06-21 08:29:05.097313 :A796B696:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16624 (legacy spawn) 2025-06-21 08:29:05.103681 :A796B697: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-06-21 08:29:05.208322 :A796B6A9: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-06-21 08:29:05.317743 :A796B6EF: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-06-21 08:29:05.427080 :A796B701: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-06-21 08:29:05.536453 :A796B713: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-06-21 08:29:05.645834 :A796B725: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-06-21 08:29:05.755214 :A796B74B: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-06-21 08:29:05.864586 :A796B75D: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-06-21 08:29:05.973975 :A796B76F: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-06-21 08:29:06.097972 :A796B7A4: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-06-21 08:29:06.207362 :A796B7B6: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-06-21 08:29:06.316732 :A796B7D4: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-06-21 08:29:06.426109 :A796B7E6: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-06-21 08:29:06.535488 :A796B81F: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-06-21 08:29:06.644864 :A796B831: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-06-21 08:29:06.754241 :A796B843: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-06-21 08:29:06.863631 :A796B85A: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-06-21 08:29:06.972956*:A796B872:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002932C6B8 estart=1750465744 eid=18180405 ctx=0x0000000777E49918 cwh=0x0000000029320560 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-06-21 08:29:06.972956*:A796B873:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002932C6B8 curCtx=0x000000002933BE50 pubTabIdxCnt=0 2025-06-21 08:29:06.972956*:A796B874:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002932C6B8 2025-06-21 08:29:06.972956*:A796B875:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002932C6B8 2025-06-21 08:29:06.973026 :A796B876: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-06-21 08:29:07.082368 :A796B888: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-06-21 08:29:07.191749 :A796B89A: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-06-21 08:29:07.301125 :A796B8B8: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-06-21 08:29:07.405050 :A796B8CA: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-06-21 08:29:07.446033 :A796B95D: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-06-21 08:29:07.447549 :A796B962: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-06-21 08:29:07.447549*:A796B964:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002932C6B8 curCtx=0x000000002933BE50 xsc->flg4=65792 flags=2 2025-06-21 08:29:07.447549*:A796B965:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002932C6B8 2025-06-21 08:29:07.447766 :A796B967: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-06-21 08:29:07.448597 :A796B9B2: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-06-21 08:29:07.449575 :A796B9BB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:29:07.476003 :A796B9BE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:29:07.468 2025-06-21 08:29:07.476005 :A796B9BF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17040 pso_num=29 pso_serial#=160 2025-06-21 08:29:07.476139 :A796B9C0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17040 (legacy spawn) 2025-06-21 08:29:07.482552 :A796B9C1: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-06-21 08:29:07.483004 :A796B9C8: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-06-21 08:29:07.483876 :A796B9CB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 08:58:59.024796 :A7993400:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 08:58:59.017 2025-06-21 08:58:59.024798 :A7993401:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12128 pso_num=29 pso_serial#=161 2025-06-21 08:58:59.024936 :A7993402:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12128 (legacy spawn) 2025-06-21 08:58:59.031498 :A7993403: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-06-21 08:58:59.031677 :A7993408: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-06-21 08:58:59.032514 :A799340D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-21 11:35:42.014397 :A7A5631B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-21 11:35:42.002 2025-06-21 11:35:42.014399 :A7A5631C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17288 pso_num=29 pso_serial#=162 2025-06-21 11:35:42.014527 :A7A5631D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17288 osp_idx=27 osp_ver=13166 osp_pg=0 (spawn #307714) 2025-06-21 11:35:42.014528 :A7A5631E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 11:35:42.014528 :A7A5631F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=95 time=3481485204 2025-06-21 11:35:42.014528 :A7A56320:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 11:35:42.014529 :A7A56321:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 11:35:42.014529 :A7A56322:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 11:35:42.014530 :A7A56323:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 11:35:42.014530 :A7A56324:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 11:35:42.015041 :A7A56325:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 11:35:42.015087 :A7A5632B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 11:35:42.019275 :A7A56344:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-21 11:35:42.019318 :A7A56345:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-21 11:35:42.019575 :A7A56346:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-21 11:35:42.019593 :A7A56347:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-21 11:35:42.019805 :A7A56348:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-21 11:35:42.019818 :A7A56349:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-21 11:35:42.020034 :A7A5634A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-21 11:35:42.020047 :A7A5634B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-21 11:35:42.020319 :A7A5634C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-21 11:35:42.020332 :A7A5634D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-21 11:35:42.020541 :A7A5634E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-21 11:35:42.020554 :A7A5634F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-21 11:35:42.022874 :A7A56350:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 17288, M001)