Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250518100016\orcl_m001_1164_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:37396M/63366M, Ph+PgF:44611M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 1164, image: ORACLE.EXE (M001) *** 2025-05-18 10:00:16.525 *** 2025-05-18 10:00:16.525 Process diagnostic dump for ORACLE.EXE (M001), OS id=1164, pid: 29, proc_ser: 8, 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-05-18 10:00:16.525 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 1164, M001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-18 08:19:58.669539 :98A8106C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 08:19:58.669539 :98A8106D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 08:19:58.669540 :98A8106E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 08:19:58.669540 :98A8106F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 08:19:58.669540 :98A81070:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 08:19:58.670061 :98A81071: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-05-18 08:19:58.670118 :98A81077: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-05-18 08:19:58.674118 :98A810A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:19:58.674181 :98A810A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:19:58.674444 :98A810A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:19:58.674464 :98A810A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:19:58.674680 :98A810A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:19:58.674693 :98A810A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:19:58.674901 :98A810A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:19:58.674914 :98A810A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:19:58.675123 :98A810A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:19:58.675136 :98A810A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:19:58.675340 :98A810AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:19:58.675353 :98A810AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:19:58.677651 :98A810AC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-18 08:50:02.676736 :98AA6B68:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-18 08:50:02.664 2025-05-18 08:50:02.676738 :98AA6B69:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2168 pso_num=29 pso_serial#=4 2025-05-18 08:50:02.676875 :98AA6B6A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=2168 osp_idx=27 osp_ver=11223 osp_pg=0 (spawn #194013) 2025-05-18 08:50:02.676876 :98AA6B6B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 08:50:02.676876 :98AA6B6C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=177 time=534012532 2025-05-18 08:50:02.676877 :98AA6B6D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 08:50:02.676877 :98AA6B6E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 08:50:02.676878 :98AA6B6F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 08:50:02.676878 :98AA6B70:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 08:50:02.676878 :98AA6B71:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 08:50:02.677381 :98AA6B72: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-05-18 08:50:02.677425 :98AA6B78: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-05-18 08:50:02.681553 :98AA6B91:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:50:02.681595 :98AA6B92:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:50:02.681879 :98AA6B93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:50:02.681905 :98AA6B94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:50:02.682162 :98AA6B95:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:50:02.682176 :98AA6B96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:50:02.682385 :98AA6B97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:50:02.682399 :98AA6B98:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:50:02.682610 :98AA6B99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:50:02.682623 :98AA6B9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:50:02.682827 :98AA6B9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 08:50:02.682839 :98AA6B9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 08:50:02.685158 :98AA6B9D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-18 09:00:03.916156 :98AB35CB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-18 09:00:03.905 2025-05-18 09:00:03.916157 :98AB35CC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12844 pso_num=29 pso_serial#=5 2025-05-18 09:00:03.916301 :98AB35CF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12844 osp_idx=27 osp_ver=11224 osp_pg=0 (spawn #194036) 2025-05-18 09:00:03.916302 :98AB35D0:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 09:00:03.916302 :98AB35D1:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=178 time=534613782 2025-05-18 09:00:03.916303 :98AB35D2:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 09:00:03.916303 :98AB35D3:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 09:00:03.916304 :98AB35D4:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 09:00:03.916304 :98AB35D5:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 09:00:03.916304 :98AB35D6:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 09:00:03.916805 :98AB35DB: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-05-18 09:00:03.916860 :98AB35E1: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-05-18 09:00:03.922369 :98AB35FA: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-05-18 09:00:03.946143 :98AB3637: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-05-18 09:00:03.960884 :98AB366B: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-05-18 09:00:03.986485 :98AB3673: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-05-18 09:00:03.990225 :98AB367B: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-05-18 09:00:03.994031 :98AB3683: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-05-18 09:00:03.998701 :98AB368B: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-05-18 09:00:03.999776 :98AB3693: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-05-18 09:00:05.143892*:98AB36F0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029371AD0 estart=1747530004 eid=16778565 ctx=0x000000077BA4B1C8 cwh=0x0000000029320AF8 cpu=484375 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-18 09:00:05.143892*:98AB36F1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029371AD0 curCtx=0x0000000028CDF998 pubTabIdxCnt=0 2025-05-18 09:00:05.143892*:98AB36F2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029371AD0 2025-05-18 09:00:05.143892*:98AB36F3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029371AD0 2025-05-18 09:00:05.143892*:98AB36F4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000029332D18 estart=1747530004 eid=16778557 ctx=0x0000000787AD5BF0 cwh=0x0000000029320410 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-18 09:00:05.143892*:98AB36F5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000029332D18 curCtx=0x0000000029372A70 pubTabIdxCnt=0 2025-05-18 09:00:05.143892*:98AB36F6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000029332D18 2025-05-18 09:00:05.143892*:98AB36F7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000029332D18 2025-05-18 09:00:05.206394*:98AB36F8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029371AD0 curCtx=0x0000000028CDF998 xsc->flg4=65792 flags=1 2025-05-18 09:00:05.206394*:98AB36F9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029371AD0 2025-05-18 09:00:05.206394*:98AB36FA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000029332D18 curCtx=0x0000000029372A70 xsc->flg4=65792 flags=2 2025-05-18 09:00:05.206394*:98AB36FB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000029332D18 2025-05-18 09:00:05.275195 :98AB36FC: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-05-18 09:00:05.303022 :98AB3710: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-05-18 09:00:05.303301 :98AB3718: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-05-18 09:00:05.303572 :98AB3720: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-05-18 09:00:05.303587 :98AB3723:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:00:05.303649 :98AB372F: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-05-18 09:00:05.306863 :98AB373D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-18 09:20:06.628541 :98ACD4AC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-18 09:20:06.616 2025-05-18 09:20:06.628543 :98ACD4AD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13976 pso_num=29 pso_serial#=6 2025-05-18 09:20:06.628684 :98ACD4AE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13976 osp_idx=27 osp_ver=11225 osp_pg=0 (spawn #194083) 2025-05-18 09:20:06.628684 :98ACD4AF:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 09:20:06.628685 :98ACD4B0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=179 time=535816485 2025-05-18 09:20:06.628685 :98ACD4B1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 09:20:06.628685 :98ACD4B2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 09:20:06.628685 :98ACD4B3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 09:20:06.628686 :98ACD4B4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 09:20:06.628686 :98ACD4B5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 09:20:06.629228 :98ACD4B6: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-05-18 09:20:06.629285 :98ACD4BC: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-05-18 09:20:06.633348 :98ACD4D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:20:06.633394 :98ACD4D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:20:06.633645 :98ACD4D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:20:06.633661 :98ACD4D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:20:06.633871 :98ACD4D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:20:06.633884 :98ACD4DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:20:06.634086 :98ACD4DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:20:06.634099 :98ACD4DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:20:06.634305 :98ACD4DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:20:06.634318 :98ACD4DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:20:06.634516 :98ACD4DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:20:06.634528 :98ACD4E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:20:06.637024 :98ACD4E1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-18 09:50:10.652666 :98AF35B0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-18 09:50:10.649 2025-05-18 09:50:10.652668 :98AF35B1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13424 pso_num=29 pso_serial#=7 2025-05-18 09:50:10.652806 :98AF35B2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13424 osp_idx=27 osp_ver=11226 osp_pg=0 (spawn #194150) 2025-05-18 09:50:10.652807 :98AF35B3:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 09:50:10.652807 :98AF35B4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=180 time=537620516 2025-05-18 09:50:10.652808 :98AF35B5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 09:50:10.652808 :98AF35B6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 09:50:10.652808 :98AF35B7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 09:50:10.652808 :98AF35B8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 09:50:10.652809 :98AF35B9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 09:50:10.653316 :98AF35BA: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-05-18 09:50:10.653363 :98AF35C0: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-05-18 09:50:10.657371 :98AF35D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:50:10.657413 :98AF35DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:50:10.657675 :98AF35DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:50:10.657691 :98AF35DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:50:10.657909 :98AF35DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:50:10.657922 :98AF35DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:50:10.658142 :98AF35DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:50:10.658156 :98AF35E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:50:10.658378 :98AF35E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:50:10.658390 :98AF35E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:50:10.658603 :98AF35E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-18 09:50:10.658616 :98AF35E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-18 09:50:10.661035 :98AF35E5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-18 10:00:11.966132 :98AFFFCE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-18 10:00:11.955 2025-05-18 10:00:11.966133 :98AFFFCF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1164 pso_num=29 pso_serial#=8 2025-05-18 10:00:11.966258 :98AFFFD2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=1164 osp_idx=27 osp_ver=11227 osp_pg=0 (spawn #194173) 2025-05-18 10:00:11.966259 :98AFFFD3:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 10:00:11.966259 :98AFFFD4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=181 time=538221829 2025-05-18 10:00:11.966260 :98AFFFD5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 10:00:11.966260 :98AFFFD6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 10:00:11.966261 :98AFFFD7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 10:00:11.966261 :98AFFFD8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 10:00:11.966261 :98AFFFD9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 10:00:11.966728 :98AFFFDE: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-05-18 10:00:11.966769 :98AFFFE4: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-05-18 10:00:11.972590 :98AFFFFC: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-05-18 10:00:11.998215 :98B0008D: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-05-18 10:00:12.005914 :98B00095: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-05-18 10:00:12.005927 :98B00096:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0004.00d.0000c05f 2025-05-18 10:00:12.006489 :98B0009E:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 4, ext 0, nex 2 2025-05-18 10:00:12.006495 :98B0009F:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00810432 where: kdiwh133: kdisdelete 2025-05-18 10:00:12.009204 :98B000A0:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 4, ext 1, nex 3 2025-05-18 10:00:12.009211 :98B000A1:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00810436 where: kdiwh22: kdifind 2025-05-18 10:00:12.011414 :98B000A2: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-05-18 10:00:12.011426 :98B000A3:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0004.015.0000c064 2025-05-18 10:00:12.011697 :98B000AB:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 4, ext 2, nex 4 2025-05-18 10:00:12.011705 :98B000AC:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081041f where: kdiwh133: kdisdelete 2025-05-18 10:00:12.014979 :98B000AD: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-05-18 10:00:12.043138 :98B0010F: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-05-18 10:00:12.046838 :98B0011F: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-05-18 10:00:12.050387 :98B0012A: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-05-18 10:00:12.050491 :98B0012C: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-05-18 10:00:12.055366 :98B0014E: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-05-18 10:00:12.056395 :98B00156: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-05-18 10:00:12.104872 :98B00267: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-05-18 10:00:13.325946 :98B00439: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-05-18 10:00:13.354728 :98B00441: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-05-18 10:00:13.355070 :98B00449: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-05-18 10:00:13.355344 :98B00451: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-05-18 10:00:13.355360 :98B00454:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.355425 :98B0045E: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-05-18 10:00:13.359193 :98B0046D: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: 1164, M001)