Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504220239\orcl_m001_10904_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:37570M/63366M, Ph+PgF:45093M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 32 Windows thread id: 10904, image: ORACLE.EXE (M001) *** 2025-05-04 22:02:39.411 *** 2025-05-04 22:02:39.411 Process diagnostic dump for ORACLE.EXE (M001), OS id=10904, pid: 32, proc_ser: 23, 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-04 22:02:39.411 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 32 (osid: 10904, M001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-02 22:00:06.004406 :91D8402C:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=10492 osp_idx=30 osp_ver=265 osp_pg=0 (spawn #141931) 2025-05-02 22:00:06.004406 :91D8402D:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:00:06.004407 :91D8402E:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=78 time=3493995484 2025-05-02 22:00:06.004407 :91D8402F:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:00:06.004407 :91D84030:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:00:06.004408 :91D84031:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:00:06.004408 :91D84032:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:00:06.004408 :91D84033:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:00:06.004914 :91D84034:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:00:06.004957 :91D8403B:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-02 22:00:06.127209 :91D84052:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:06.127578 :91D84065:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-02 22:00:06.939986 :91D840D4:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-02 22:00:06.931 2025-05-02 22:00:06.939988 :91D840D5:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=11840 pso_num=32 pso_serial#=12 2025-05-02 22:00:06.940126 :91D840D6:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=11840 (legacy spawn) 2025-05-02 22:00:06.946551 :91D840D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:06.946708 :91D840E0:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-02 22:00:06.947524 :91D840E1:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-02 22:00:06.956029 :91D840E4:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-02 22:00:06.947 2025-05-02 22:00:06.956031 :91D840E5:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=11180 pso_num=32 pso_serial#=13 2025-05-02 22:00:06.956162 :91D840E6:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=11180 (legacy spawn) 2025-05-02 22:00:06.962580 :91D840E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:06.962743 :91D840F0:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-02 22:00:06.963516 :91D840F1:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-02 23:59:14.109113 :91E209DD:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-02 23:59:14.100 2025-05-02 23:59:14.109116 :91E209DE:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=3352 pso_num=32 pso_serial#=14 2025-05-02 23:59:14.109252 :91E209DF:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=3352 (legacy spawn) 2025-05-02 23:59:14.122934 :91E209E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 23:59:17.413855 :91E20B10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 23:59:17.414027 :91E20B15:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-02 23:59:17.415207 :91E20B1A:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-02 23:59:17.666274 :91E20B1D:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-02 23:59:17.662 2025-05-02 23:59:17.666276 :91E20B1E:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=11332 pso_num=32 pso_serial#=15 2025-05-02 23:59:17.666416 :91E20B1F:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=11332 (legacy spawn) 2025-05-02 23:59:17.680587 :91E20B20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 23:59:50.847733 :91E21712:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-02 23:59:59.105860 :91E21A14:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-02 23:59:59.099 2025-05-02 23:59:59.105862 :91E21A15:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=11116 pso_num=32 pso_serial#=16 2025-05-02 23:59:59.105993 :91E21A16:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=11116 (legacy spawn) 2025-05-02 23:59:59.119713 :91E21A17:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 00:06:19.082023 :91E2A7F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 00:06:19.082197 :91E2A7FA:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-03 00:06:19.083259 :91E2A7FC:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-03 06:00:03.214474 :91FEDD17:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-03 06:00:03.208 2025-05-03 06:00:03.214476 :91FEDD18:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=6556 pso_num=32 pso_serial#=17 2025-05-03 06:00:03.214615 :91FEDD19:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=6556 osp_idx=30 osp_ver=271 osp_pg=0 (spawn #143070) 2025-05-03 06:00:03.214616 :91FEDD1A:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 06:00:03.214616 :91FEDD1B:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=79 time=3522792687 2025-05-03 06:00:03.214617 :91FEDD1C:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 06:00:03.214617 :91FEDD1D:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 06:00:03.214618 :91FEDD1E:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 06:00:03.214618 :91FEDD1F:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 06:00:03.214618 :91FEDD20:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 06:00:03.215110 :91FEDD21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.215162 :91FEDD27:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-03 06:00:03.219410 :91FEDD3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.219439 :91FEDD3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=25 loc='kqr.h LINE:2208 ID:kqrrls: post after conversion ' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.246748 :91FEDD5C:db_trace:ksb.c@6610:ksbcic_int(): [10254:32:2] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286467835 8499464) 2025-05-03 06:00:03.246756 :91FEDD5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.246803 :91FEDD65:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 06:00:03.246879 :91FEDD75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.254693 :91FEDD7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.254703 :91FEDD7E:db_trace:ktur.c@3079:ktuabt(): [10444:32:2] ABORT TRANSACTION - xid: 0x0001.01a.0000beeb 2025-05-03 06:00:03.255216 :91FEDD86:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 1, ext 0, nex 2 2025-05-03 06:00:03.255222 :91FEDD87:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x0080eebf where: kdiwh187: kdisprobe 2025-05-03 06:00:03.257423 :91FEDD90:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 1, ext 1, nex 3 2025-05-03 06:00:03.257430 :91FEDD91:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x008014c4 where: kdiwh22: kdifind 2025-05-03 06:00:03.259905 :91FEDD92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.259915 :91FEDD93:db_trace:ktur.c@3079:ktuabt(): [10444:32:2] ABORT TRANSACTION - xid: 0x0001.005.0000beeb 2025-05-03 06:00:03.260123 :91FEDD9B:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 1, ext 2, nex 4 2025-05-03 06:00:03.260129 :91FEDD9C:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x0080f473 where: kdiwh133: kdisdelete 2025-05-03 06:00:03.262949 :91FEDD9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.288885 :91FEDDD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.292672 :91FEDDE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.296181 :91FEDDF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.300210 :91FEDDF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:03.301195 :91FEDE01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:04.290693*:91FEDF8B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000039368618 estart=1746223203 eid=16778199 ctx=0x000000077BA4B1C8 cwh=0x00000000393205F0 cpu=375000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-03 06:00:04.290693*:91FEDF8C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000039368618 curCtx=0x000000003938AD38 pubTabIdxCnt=0 2025-05-03 06:00:04.290693*:91FEDF8D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000039368618 2025-05-03 06:00:04.290693*:91FEDF8E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000039368618 2025-05-03 06:00:04.290693*:91FEDF8F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000039365C28 estart=1746223203 eid=16778194 ctx=0x0000000787AD5BF0 cwh=0x0000000039320918 cpu=375000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-03 06:00:04.290693*:91FEDF90:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000039365C28 curCtx=0x000000003938CC40 pubTabIdxCnt=0 2025-05-03 06:00:04.290693*:91FEDF91:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000039365C28 2025-05-03 06:00:04.290693*:91FEDF92:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000039365C28 2025-05-03 06:00:04.462555*:91FEE032:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000039368618 curCtx=0x000000003938AD38 xsc->flg4=65792 flags=1 2025-05-03 06:00:04.462555*:91FEE033:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000039368618 2025-05-03 06:00:04.462555*:91FEE034:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000039365C28 curCtx=0x000000003938CC40 xsc->flg4=65792 flags=2 2025-05-03 06:00:04.462555*:91FEE035:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000039365C28 2025-05-03 06:00:04.543673 :91FEE036:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:04.576149 :91FEE03E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:04.576461 :91FEE046:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:04.576731 :91FEE04E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:04.576746 :91FEE051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:04.576824 :91FEE061:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-03 06:00:04.580669 :91FEE06B:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-03 06:00:06.202145 :91FEE16B:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-03 06:00:06.197 2025-05-03 06:00:06.202147 :91FEE16C:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=10896 pso_num=32 pso_serial#=18 2025-05-03 06:00:06.202286 :91FEE16D:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=10896 osp_idx=30 osp_ver=272 osp_pg=0 (spawn #143072) 2025-05-03 06:00:06.202287 :91FEE16E:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 06:00:06.202287 :91FEE16F:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=80 time=3522795687 2025-05-03 06:00:06.202288 :91FEE170:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 06:00:06.202288 :91FEE171:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 06:00:06.202288 :91FEE172:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 06:00:06.202289 :91FEE173:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 06:00:06.202289 :91FEE174:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 06:00:06.202789 :91FEE175:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 06:00:06.202833 :91FEE17C:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-03 06:00:06.345853 :91FEE193:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:06.346230 :91FEE1A6:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-03 06:00:08.089157 :91FEE2DF:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-03 06:00:08.082 2025-05-03 06:00:08.089159 :91FEE2E0:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=10408 pso_num=32 pso_serial#=19 2025-05-03 06:00:08.089288 :91FEE2E1:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=10408 (legacy spawn) 2025-05-03 06:00:08.095632 :91FEE2E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 06:00:08.095804 :91FEE2EB:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-03 06:00:08.096563 :91FEE2EC:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-03 23:59:21.074810 :9255E453:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-03 23:59:21.069 2025-05-03 23:59:21.074812 :9255E454:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=8868 pso_num=32 pso_serial#=20 2025-05-03 23:59:21.074948 :9255E455:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=8868 osp_idx=30 osp_ver=274 osp_pg=0 (spawn #145613) 2025-05-03 23:59:21.074949 :9255E456:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 23:59:21.074949 :9255E457:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=81 time=3587538234 2025-05-03 23:59:21.074950 :9255E458:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 23:59:21.074950 :9255E459:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 23:59:21.074950 :9255E45A:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 23:59:21.074951 :9255E45B:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 23:59:21.074951 :9255E45C:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 23:59:21.075538 :9255E45D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 23:59:21.076204 :9255E472:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 23:59:21.083792 :9255E48E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:psdpgi.c:2235 2025-05-03 23:59:21.084170 :9255E48F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 23:59:21.084213 :9255E492:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:psdpgi.c:2235 2025-05-03 23:59:21.085123 :9255E498:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 23:59:21.085254 :9255E4A1:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-03 23:59:51.153062 :9255F0B1:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-03 23:59:58.840059 :9255F326:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-03 23:59:58.829 2025-05-03 23:59:58.840062 :9255F327:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=10760 pso_num=32 pso_serial#=21 2025-05-03 23:59:58.840199 :9255F328:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=10760 (legacy spawn) 2025-05-03 23:59:58.864457 :9255F329:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 00:06:18.811469 :9256809F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 00:06:18.811635 :925680A4:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-04 00:06:18.812838 :925680A9:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-04 06:00:07.281726 :92728867:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-04 06:00:07.276 2025-05-04 06:00:07.281728 :92728868:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=12012 pso_num=32 pso_serial#=22 2025-05-04 06:00:07.281869 :92728869:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=12012 osp_idx=30 osp_ver=276 osp_pg=0 (spawn #146470) 2025-05-04 06:00:07.281870 :9272886A:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 06:00:07.281870 :9272886B:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=82 time=3609184453 2025-05-04 06:00:07.281870 :9272886C:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 06:00:07.281871 :9272886D:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 06:00:07.281871 :9272886E:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 06:00:07.281872 :9272886F:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 06:00:07.281872 :92728870:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 06:00:07.282408 :92728871:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 06:00:07.282453 :92728878:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-04 06:00:07.425533 :9272888F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 06:00:07.425947 :927288A2:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-05-04 06:00:25.344551 :92729093:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 05-04 06:00:25.332 2025-05-04 06:00:25.344553 :92729094:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=10904 pso_num=32 pso_serial#=23 2025-05-04 06:00:25.344689 :92729095:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=10904 osp_idx=30 osp_ver=277 osp_pg=0 (spawn #146473) 2025-05-04 06:00:25.344690 :92729096:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 06:00:25.344690 :92729097:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=83 time=3609202500 2025-05-04 06:00:25.344691 :92729098:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 06:00:25.344691 :92729099:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 06:00:25.344691 :9272909A:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 06:00:25.344692 :9272909B:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 06:00:25.344692 :9272909C:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 06:00:25.345210 :9272909D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 06:00:25.345255 :927290A3:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-04 06:00:25.349322 :927290B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:kelt.c:517 2025-05-04 06:00:25.349388 :927290B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:kelt.c:517 2025-05-04 06:00:25.349646 :927290B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:kelt.c:517 2025-05-04 06:00:25.349662 :927290B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:kelt.c:517 2025-05-04 06:00:25.349889 :927290BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:kelt.c:517 2025-05-04 06:00:25.349902 :927290BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:kelt.c:517 2025-05-04 06:00:25.350110 :927290BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:kelt.c:517 2025-05-04 06:00:25.350123 :927290BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:kelt.c:517 2025-05-04 06:00:25.350332 :927290C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:kelt.c:517 2025-05-04 06:00:25.350346 :927290C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:kelt.c:517 2025-05-04 06:00:25.350551 :927290CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:kelt.c:517 2025-05-04 06:00:25.350564 :927290CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:kelt.c:517 2025-05-04 06:00:25.352996 :927290F0:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 32 (osid: 10904, M001)