Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629221114\orcl_j000_16800_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:36546M/63366M, Ph+PgF:42663M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 16800, image: ORACLE.EXE (J000) *** 2025-06-29 22:11:14.812 *** 2025-06-29 22:11:14.812 Process diagnostic dump for ORACLE.EXE (J000), OS id=16800, pid: 22, proc_ser: 126, 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-29 22:11:14.812 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 16800, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 22:08:58.123271 :AB633154:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:08:59.420068 :AB63317B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:08:59.415 2025-06-29 22:08:59.420070 :AB63317C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16840 pso_num=22 pso_serial#=116 2025-06-29 22:08:59.420203 :AB63317D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16840 osp_idx=0 osp_ver=360758 osp_pg=0 (spawn #335856) 2025-06-29 22:08:59.420204 :AB63317E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 22:08:59.420204 :AB63317F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=141 time=4210682610 2025-06-29 22:08:59.420204 :AB633180:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 22:08:59.420205 :AB633181:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 22:08:59.420205 :AB633182:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 22:08:59.420206 :AB633183:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 22:08:59.420206 :AB633184:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 22:08:59.420703 :AB633185:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 22:08:59.421413 :AB63319A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:08:59.432487 :AB6331B7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 22:08:59.432992 :AB6331B8:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 22:08:59.435342 :AB6331B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 22:08:59.435671 :AB6331BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:08:59.435705 :AB6331BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 22:08:59.436563 :AB6331C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:08:59.436692 :AB6331CC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:29.512667 :AB633B47:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:31.308186 :AB633C18:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:31.293 2025-06-29 22:09:31.308188 :AB633C19:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18296 pso_num=22 pso_serial#=117 2025-06-29 22:09:31.308323 :AB633C1A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18296 (legacy spawn) 2025-06-29 22:09:31.314775 :AB633C1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:31.314947 :AB633C21:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:31.315735 :AB633C25:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:31.324318 :AB633C28:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:31.325 2025-06-29 22:09:31.324320 :AB633C29:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12572 pso_num=22 pso_serial#=118 2025-06-29 22:09:31.324462 :AB633C2A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12572 (legacy spawn) 2025-06-29 22:09:31.330880 :AB633C2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:31.331052 :AB633C34:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:31.331887 :AB633C35:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:34.410138 :AB633D1F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:34.403 2025-06-29 22:09:34.410139 :AB633D20:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16076 pso_num=22 pso_serial#=119 2025-06-29 22:09:34.410278 :AB633D21:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16076 (legacy spawn) 2025-06-29 22:09:34.416689 :AB633D22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:34.416855 :AB633D27:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:34.417656 :AB633D2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:34.426444 :AB633D2F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:34.419 2025-06-29 22:09:34.426446 :AB633D30:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18336 pso_num=22 pso_serial#=120 2025-06-29 22:09:34.426588 :AB633D31:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18336 (legacy spawn) 2025-06-29 22:09:34.433044 :AB633D32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:34.433215 :AB633D3B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:34.434060 :AB633D3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:50.903129 :AB634222:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:50.894 2025-06-29 22:09:50.903131 :AB634223:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4120 pso_num=22 pso_serial#=121 2025-06-29 22:09:50.903271 :AB634224:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4120 (legacy spawn) 2025-06-29 22:09:50.909810 :AB634225:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:50.909989 :AB63422D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:50.910785 :AB63422F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:54.198444 :AB634318:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:54.185 2025-06-29 22:09:54.198446 :AB634319:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16768 pso_num=22 pso_serial#=122 2025-06-29 22:09:54.198594 :AB63431A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16768 (legacy spawn) 2025-06-29 22:09:54.205279 :AB63431B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:54.205445 :AB634320:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:54.206224 :AB634325:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:54.225293 :AB634328:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:54.217 2025-06-29 22:09:54.225295 :AB634329:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18264 pso_num=22 pso_serial#=123 2025-06-29 22:09:54.225430 :AB63432A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18264 (legacy spawn) 2025-06-29 22:09:54.231983 :AB63432B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:54.232151 :AB634333:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:54.232947 :AB634335:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:58.341976 :AB6344EB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:58.333 2025-06-29 22:09:58.341978 :AB6344EC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9416 pso_num=22 pso_serial#=124 2025-06-29 22:09:58.342114 :AB6344ED:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9416 (legacy spawn) 2025-06-29 22:09:58.348630 :AB6344EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:58.348823 :AB6344F3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:09:58.349628 :AB6344F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:09:59.511004 :AB63451F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:09:59.505 2025-06-29 22:09:59.511006 :AB634520:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16340 pso_num=22 pso_serial#=125 2025-06-29 22:09:59.511144 :AB634521:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16340 osp_idx=0 osp_ver=360767 osp_pg=0 (spawn #335858) 2025-06-29 22:09:59.511145 :AB634522:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 22:09:59.511145 :AB634523:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=142 time=4210742704 2025-06-29 22:09:59.511145 :AB634524:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 22:09:59.511146 :AB634525:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 22:09:59.511146 :AB634526:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 22:09:59.511146 :AB634527:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 22:09:59.511146 :AB634528:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 22:09:59.511632 :AB634529:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 22:09:59.512344 :AB63453E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:59.519855 :AB63455A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 22:09:59.520275 :AB63455B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:59.520311 :AB63455E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 22:09:59.521166 :AB634564:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:09:59.521288 :AB63456D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:10:24.533149 :AB634DF2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:10:24.533170*:AB634DF4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1751206224 eid=16933413 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 22:10:24.533170*:AB634DF5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-06-29 22:10:24.533170*:AB634DF6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-06-29 22:10:24.533170*:AB634DF7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-06-29 22:10:24.533170*:AB634DF8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-06-29 22:10:24.533170*:AB634DF9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-06-29 22:10:24.533729 :AB634DFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:10:24.539094 :AB634E02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:10:24.539956 :AB634E0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:10:24.540090 :AB634E13:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:10:54.644219 :AB635AD6:db_trace:ksl2.c@2324:kslwtbctx(): [10005:22:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-06-29 22:10:54.644229 :AB635AD7:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-06-29 22:10:54.644229 :AB635AD8:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:0] KSL WAIT END wait times (usecs) - snap=10, exc=10, tot=10 2025-06-29 22:10:54.644470 :AB635ADC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 22:10:59.591858 :AB635C6C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 22:10:59.586 2025-06-29 22:10:59.591860 :AB635C6D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16800 pso_num=22 pso_serial#=126 2025-06-29 22:10:59.591996 :AB635C6E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16800 osp_idx=0 osp_ver=360768 osp_pg=0 (spawn #335860) 2025-06-29 22:10:59.591997 :AB635C6F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 22:10:59.591997 :AB635C70:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=143 time=4210802782 2025-06-29 22:10:59.591997 :AB635C71:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 22:10:59.591998 :AB635C72:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 22:10:59.591998 :AB635C73:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 22:10:59.591998 :AB635C74:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 22:10:59.591999 :AB635C75:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 22:10:59.592485 :AB635C76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 22:10:59.593115 :AB635C8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:10:59.604109 :AB635CA7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 22:10:59.604570 :AB635CA8:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-29 22:10:59.606954 :AB635CA9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 22:10:59.607276 :AB635CAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:10:59.607312 :AB635CAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 22:10:59.608170 :AB635CB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:10:59.608288 :AB635CBC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:11:06.664148 :AB63605C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22:11:06.664148*:AB63606E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1751206266 eid=16783269 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 22:11:06.664148*:AB63606F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-06-29 22:11:06.664148*:AB636070:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-06-29 22:11:06.664148*:AB636071:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-06-29 22:11:06.664148*:AB636072:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-06-29 22:11:06.664148*:AB636073:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-06-29 22:11:06.666955 :AB636074:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.667013 :AB636077:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-06-29 22:11:06.687531 :AB6360A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.687753 :AB6360B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.687925 :AB6360B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.688081 :AB6360C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.688244 :AB6360C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.688387 :AB6360D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.688586 :AB6360D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.688739 :AB6360E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.688912 :AB6360E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.689069 :AB6360F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.689422 :AB6360F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.689674 :AB636100:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:06.710126 :AB636108:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.176588*:AB6361EB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B93AD0 estart=1751206268 eid=19946430 ctx=0x0000000777968158 cwh=0x000000002DB40068 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-29 22:11:08.176588*:AB6361EC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B93AD0 curCtx=0x000000002DC019A8 pubTabIdxCnt=0 2025-06-29 22:11:08.176588*:AB6361ED:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B93AD0 2025-06-29 22:11:08.176588*:AB6361EE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B93AD0 2025-06-29 22:11:08.176588*:AB6361EF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BFB820 estart=1751206266 eid=16777425 ctx=0x000000077B54EAC8 cwh=0x000000000FD00608 cpu=1250000 buffg=68507 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-29 22:11:08.176588*:AB6361F0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BFB820 curCtx=0x000000002DC09FF0 pubTabIdxCnt=0 2025-06-29 22:11:08.176588*:AB6361F1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BFB820 2025-06-29 22:11:08.176588*:AB6361F2:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BFB820 2025-06-29 22:11:08.176588*:AB6361F3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B93AD0 curCtx=0x000000002DC019A8 xsc->flg4=65792 flags=1 2025-06-29 22:11:08.176588*:AB6361F4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B93AD0 2025-06-29 22:11:08.410945*:AB6362D3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BFB820 curCtx=0x000000002DC09FF0 xsc->flg4=65792 flags=2 2025-06-29 22:11:08.410945*:AB6362D4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BFB820 2025-06-29 22:11:08.414266 :AB6362D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.436599 :AB6362DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.438849 :AB6362E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-29 22:11:08.439074 :AB6362E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-29 22:11:08.440057 :AB6362E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.440167 :AB6362EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.441199 :AB6362FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.442964 :AB636304:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.443267 :AB63630C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:08.489066*: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-29 22:11:08.489066*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-29 22:11:08.489066*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-29 22:11:08.520318*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-29 22:11:08.520318*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-29 22:11:08.520318*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-29 22:11:08.520318*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-29 22:11:08.589002 :AB636314:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:10.533233 :AB636407:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:10.533245 :AB636408:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x000a.008.0017076f 2025-06-29 22:11:13.023017 :AB63653E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:13.024472 :AB636546:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:13.035253 :AB63654E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:13.061490 :AB636556:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:14.248479 :AB636670:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-06-29 22:11:14.258634 :AB636671:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 22:11:14.260246 :AB636679:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 16800, J000)