Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906220443\orcl_m002_23808_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:35011M/63366M, Ph+PgF:39835M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 23808, image: ORACLE.EXE (M002) *** 2025-09-06 22:04:43.715 *** 2025-09-06 22:04:43.715 Process diagnostic dump for ORACLE.EXE (M002), OS id=23808, pid: 30, proc_ser: 69, 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-09-06 22:04:43.715 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 23808, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 01:00:09.593372 :C98CE1E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 01:00:09.594339 :C98CE1EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 01:00:09.594581 :C98CE1F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 01:00:09.594808 :C98CE1FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 01:00:09.596586 :C98CE206:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 01:02:08.439451 :C98D0B4A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 01:02:08.436 2025-09-06 01:02:08.439452 :C98D0B4B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24088 pso_num=30 pso_serial#=62 2025-09-06 01:02:08.439586 :C98D0B4C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=24088 osp_idx=28 osp_ver=2101 osp_pg=0 (spawn #562914) 2025-09-06 01:02:08.439587 :C98D0B4D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 01:02:08.439587 :C98D0B4E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=63 time=1506179737 2025-09-06 01:02:08.439588 :C98D0B4F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 01:02:08.439588 :C98D0B50:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 01:02:08.439588 :C98D0B51:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 01:02:08.439589 :C98D0B52:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 01:02:08.439589 :C98D0B53:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 01:02:08.440128 :C98D0B54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 01:02:08.440192 :C98D0B5A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 01:02:08.449420 :C98D0B72:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 06:00:00.589906 :C9A45270:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 06:00:00.577 2025-09-06 06:00:00.589907 :C9A45271:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=22996 pso_num=30 pso_serial#=63 2025-09-06 06:00:00.590033 :C9A45272:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=22996 osp_idx=28 osp_ver=2102 osp_pg=0 (spawn #563603) 2025-09-06 06:00:00.590034 :C9A45273:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 06:00:00.590035 :C9A45274:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=64 time=1524051893 2025-09-06 06:00:00.590035 :C9A45275:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 06:00:00.590036 :C9A45276:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 06:00:00.590036 :C9A45277:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 06:00:00.590036 :C9A45278:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 06:00:00.590037 :C9A45279:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 06:00:00.590629 :C9A45282:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 06:00:00.595904 :C9A453DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:00.595943 :C9A453DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-09-06 06:00:00.601468 :C9A45428:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:00.769657 :C9A4555A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:00.930828 :C9A455AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:01.061268 :C9A455CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.179228 :C9A457C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.180275 :C9A457CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.186013 :C9A457D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.188031 :C9A45827:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.189494 :C9A4582F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.205815 :C9A45837:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.206498 :C9A4583F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.207009 :C9A45847:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.207511 :C9A4584F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.210149 :C9A45857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.211073 :C9A4585F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.211832 :C9A45860:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.212599 :C9A45861:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.213026 :C9A45862:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.213032 :C9A45863:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.213635 :C9A45864:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.214144 :C9A45865:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.214426 :C9A45866:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.215510 :C9A45867:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.258017 :C9A45868:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.258066 :C9A4586B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.258957 :C9A45871:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.259824 :C9A45879:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.260304 :C9A4587A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.280967 :C9A45893:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.281019 :C9A45896:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.281019*:C9A4589C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000326BE348 estart=1757109602 eid=16778971 ctx=0x0000000773524750 cwh=0x0000000032700158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-09-06 06:00:02.281019*:C9A4589D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000326BE348 curCtx=0x000000003271FBF0 pubTabIdxCnt=0 2025-09-06 06:00:02.281019*:C9A4589E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000326BE348 2025-09-06 06:00:02.281019*:C9A4589F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000326BE348 2025-09-06 06:00:02.281019*:C9A458A0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000326BE348 curCtx=0x000000003271FBF0 xsc->flg4=65792 flags=1 2025-09-06 06:00:02.281019*:C9A458A1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000326BE348 2025-09-06 06:00:02.281911 :C9A458A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.282753 :C9A458AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.283217 :C9A458AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.302178 :C9A458B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.302236 :C9A458B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.303100 :C9A458BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.314753 :C9A458D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-06 06:00:02.315252 :C9A458D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.335343 :C9A458E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.335395 :C9A458E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-06 06:00:02.336299 :C9A458F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.336760 :C9A458F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.337124 :C9A45917:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.337309 :C9A4591F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.337448 :C9A45927:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.337596 :C9A4592F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-09-06 06:00:02.338775 :C9A45938:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.340204 :C9A4594B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] 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-09-06 06:00:02.341593 :C9A45952:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 06:00:02.341665 :C9A45955:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-06 06:00:32.378723 :C9A46624:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 18:04:11.201809 :C9DC8A01:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 18:04:11.188 2025-09-06 18:04:11.201811 :C9DC8A02:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=21868 pso_num=30 pso_serial#=64 2025-09-06 18:04:11.201958 :C9DC8A03:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=21868 (legacy spawn) 2025-09-06 18:04:11.209177 :C9DC8A04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:11.209364 :C9DC8A0D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 18:04:11.210295 :C9DC8A0E:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 18:04:11.219581 :C9DC8A11:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 18:04:11.219 2025-09-06 18:04:11.219583 :C9DC8A12:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24420 pso_num=30 pso_serial#=65 2025-09-06 18:04:11.219733 :C9DC8A13:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=24420 (legacy spawn) 2025-09-06 18:04:11.226593 :C9DC8A14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:11.226630 :C9DC8A17:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 18:04:11.226813 :C9DC8A1D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 18:04:11.235832 :C9DC8A2B:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 19:04:17.633785 :C9E12B48:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 19:04:17.630 2025-09-06 19:04:17.633786 :C9E12B49:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23648 pso_num=30 pso_serial#=66 2025-09-06 19:04:17.633946 :C9E12B4A:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23648 osp_idx=28 osp_ver=2105 osp_pg=0 (spawn #565426) 2025-09-06 19:04:17.633947 :C9E12B4B:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 19:04:17.633948 :C9E12B4C:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=65 time=1571108924 2025-09-06 19:04:17.633949 :C9E12B4D:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 19:04:17.633949 :C9E12B4E:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 19:04:17.633950 :C9E12B4F:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 19:04:17.633950 :C9E12B50:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 19:04:17.633950 :C9E12B51:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 19:04:17.634611 :C9E12B52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 19:04:17.634661 :C9E12B59:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 19:04:17.643700 :C9E12B70:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 20:04:24.712812 :C9E5C9D0:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 20:04:24.707 2025-09-06 20:04:24.712813 :C9E5C9D1:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23144 pso_num=30 pso_serial#=67 2025-09-06 20:04:24.712968 :C9E5C9D2:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23144 osp_idx=28 osp_ver=2106 osp_pg=0 (spawn #565565) 2025-09-06 20:04:24.712969 :C9E5C9D3:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 20:04:24.712970 :C9E5C9D4:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=66 time=1574716002 2025-09-06 20:04:24.712971 :C9E5C9D5:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 20:04:24.712971 :C9E5C9D6:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 20:04:24.712972 :C9E5C9D7:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 20:04:24.712972 :C9E5C9D8:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 20:04:24.712973 :C9E5C9D9:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 20:04:24.713585 :C9E5C9DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 20:04:24.713633 :C9E5C9E0:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 20:04:24.723158 :C9E5C9F8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 21:04:31.710667 :C9EA67C4:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 21:04:31.707 2025-09-06 21:04:31.710668 :C9EA67C5:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24192 pso_num=30 pso_serial#=68 2025-09-06 21:04:31.710818 :C9EA67C6:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=24192 osp_idx=28 osp_ver=2107 osp_pg=0 (spawn #565705) 2025-09-06 21:04:31.710819 :C9EA67C7:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 21:04:31.710820 :C9EA67C8:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=67 time=1578323002 2025-09-06 21:04:31.710820 :C9EA67C9:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 21:04:31.710820 :C9EA67CA:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 21:04:31.710821 :C9EA67CB:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 21:04:31.710821 :C9EA67CC:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 21:04:31.710821 :C9EA67CD:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 21:04:31.711372 :C9EA67CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 21:04:31.711421 :C9EA67D4:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 21:04:31.720784 :C9EA67EC:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-06 22:04:39.105735 :C9EF0859:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-06 22:04:39.101 2025-09-06 22:04:39.105736 :C9EF085A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23808 pso_num=30 pso_serial#=69 2025-09-06 22:04:39.105876 :C9EF085B:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23808 osp_idx=28 osp_ver=2108 osp_pg=0 (spawn #565843) 2025-09-06 22:04:39.105877 :C9EF085C:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 22:04:39.105877 :C9EF085D:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=68 time=1581930393 2025-09-06 22:04:39.105878 :C9EF085E:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 22:04:39.105878 :C9EF085F:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 22:04:39.105879 :C9EF0860:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 22:04:39.105879 :C9EF0861:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 22:04:39.105879 :C9EF0862:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 22:04:39.106406 :C9EF0863:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.106452 :C9EF086A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-06 22:04:39.106538 :C9EF0874:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.111104 :C9EF0880:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.167041 :C9EF08BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.170913 :C9EF08C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.171507 :C9EF08CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.171529 :C9EF08CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.171573 :C9EF08DA:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 22:04:39.172533 :C9EF08E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.172550 :C9EF08E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.172581 :C9EF08F3:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 22:04:39.173041 :C9EF08FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.175487 :C9EF0906:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.180674 :C9EF090E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.186197 :C9EF0916:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.187487 :C9EF091E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.190611 :C9EF0926:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.191730 :C9EF092E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.191743 :C9EF092F:db_trace:ktur.c@3079:ktuabt(): [10444:30:241] ABORT TRANSACTION - xid: 0x000a.010.0017b96a 2025-09-06 22:04:39.195060 :C9EF0936:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.195213 :C9EF0945:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.195507 :C9EF0950:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.195558 :C9EF0953:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:ktcc.c:879 2025-09-06 22:04:39.195577 :C9EF0954:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:ktcc.c:879 2025-09-06 22:04:39.198365 :C9EF0972:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.198463 :C9EF0981:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.198673 :C9EF0989:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.198714 :C9EF098C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:ktcc.c:879 2025-09-06 22:04:39.198728 :C9EF098D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:ktcc.c:879 2025-09-06 22:04:39.198947 :C9EF0993:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.198958 :C9EF0996:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.198985 :C9EF09A2:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 22:04:39.200968 :C9EF09AC:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 23808, M002)