Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250908222147\orcl_m002_23444_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:34940M/63366M, Ph+PgF:39702M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 23444, image: ORACLE.EXE (M002) *** 2025-09-08 22:21:47.190 *** SESSION ID:(233.34651) 2025-09-08 22:21:47.190 *** 2025-09-08 22:21:47.190 Process diagnostic dump for ORACLE.EXE (M002), OS id=23444, pid: 29, proc_ser: 165, sid: 233, sess_ser: 34651 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x1b8a, blocks=0x1 wait_id=1212 seq_num=1213 snap_id=1 wait times: snap=0.000692 sec, exc=0.000692 sec, total=0.000692 sec wait times: max=infinite, heur=0.000692 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000139 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x7b2, blocks=0x1 wait_id=1211 seq_num=1212 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x1d3a, blocks=0x1 wait_id=1210 seq_num=1211 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000151 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x1a2a, blocks=0x1 wait_id=1209 seq_num=1210 snap_id=1 wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000272 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1c5a, blocks=0x1 wait_id=1208 seq_num=1209 snap_id=1 wait times: snap=0.001823 sec, exc=0.001823 sec, total=0.001823 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000337 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x1baa, blocks=0x1 wait_id=1207 seq_num=1208 snap_id=1 wait times: snap=0.003460 sec, exc=0.003460 sec, total=0.003460 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000141 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1d2a, blocks=0x1 wait_id=1206 seq_num=1207 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000150 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1b2a, blocks=0x1 wait_id=1205 seq_num=1206 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000157 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x7ca, blocks=0x1 wait_id=1204 seq_num=1205 snap_id=1 wait times: snap=0.000072 sec, exc=0.000072 sec, total=0.000072 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000286 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x7d2, blocks=0x1 wait_id=1203 seq_num=1204 snap_id=1 wait times: snap=0.002505 sec, exc=0.002505 sec, total=0.002505 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000154 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x6b2, blocks=0x1 wait_id=1202 seq_num=1203 snap_id=1 wait times: snap=0.000805 sec, exc=0.000805 sec, total=0.000805 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000131 sec of elapsed time Sampled Session History of session 233 serial 34651 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [1 sample, 22:21:47] idle wait at each sample [session created at: 22:21:46] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-08 22:21:47.190 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 23444, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-08 22:00:02.049342 :CAD27B03:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23816 pso_num=29 pso_serial#=156 2025-09-08 22:00:02.049461 :CAD27B04:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23816 osp_idx=27 osp_ver=17240 osp_pg=0 (spawn #572495) 2025-09-08 22:00:02.049462 :CAD27B05:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:00:02.049463 :CAD27B06:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=211 time=1754453346 2025-09-08 22:00:02.049464 :CAD27B08:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:00:02.049465 :CAD27B09:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:00:02.049465 :CAD27B0A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:00:02.049466 :CAD27B0B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:00:02.049466 :CAD27B0C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:00:02.050000 :CAD27B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.055061 :CAD27B34: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-09-08 22:00:02.055102 :CAD27B37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-09-08 22:00:02.072315 :CAD27CF8: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-09-08 22:00:02.072510 :CAD27D00: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-09-08 22:00:02.072846 :CAD27D17: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-09-08 22:00:02.073004 :CAD27D1F: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-09-08 22:00:02.073173 :CAD27D27: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-09-08 22:00:02.073463 :CAD27D2F: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-09-08 22:00:02.073632 :CAD27D37: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-09-08 22:00:02.073787 :CAD27D40: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-09-08 22:00:02.073947 :CAD27D48: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-09-08 22:00:02.074122 :CAD27D50: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-09-08 22:00:02.074258 :CAD27D5B: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-09-08 22:00:02.074281 :CAD27D5D: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-09-08 22:00:02.074451 :CAD27D6F: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-09-08 22:00:02.074714 :CAD27D77: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-09-08 22:00:02.074932 :CAD27D7F: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-09-08 22:00:02.075090 :CAD27D87: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-09-08 22:00:02.075250 :CAD27D91: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-09-08 22:00:02.075404 :CAD27D99: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-09-08 22:00:02.075557 :CAD27DA1: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-09-08 22:00:02.075710 :CAD27DAC: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-09-08 22:00:02.075858 :CAD27DC5: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-09-08 22:00:02.076012 :CAD27DCD: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-09-08 22:00:02.076163 :CAD27DD5: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-09-08 22:00:02.076763 :CAD27DDD: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-09-08 22:00:02.076940 :CAD27DE5: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-09-08 22:00:02.077180 :CAD27DED: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-09-08 22:00:02.104905 :CAD27DF5: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-09-08 22:00:03.204777*:CAD2801F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1757340003 eid=22224518 ctx=0x0000000777968158 cwh=0x000000005DF60158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-09-08 22:00:03.204777*:CAD28020:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x000000005DE61BF8 pubTabIdxCnt=0 2025-09-08 22:00:03.204777*:CAD28021:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-09-08 22:00:03.204777*:CAD28022:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-09-08 22:00:03.204777*:CAD28023:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301FB6F0 estart=1757340002 eid=16777576 ctx=0x000000077B54EAC8 cwh=0x00000000301F0068 cpu=921875 buffg=51701 interb=196608 r=24 rb=196608 w=0 wb=0 2025-09-08 22:00:03.204777*:CAD28024:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301FB6F0 curCtx=0x000000005DE57FD8 pubTabIdxCnt=0 2025-09-08 22:00:03.204777*:CAD28025:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301FB6F0 2025-09-08 22:00:03.204777*:CAD28026:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301FB6F0 2025-09-08 22:00:03.204777*:CAD28027:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x000000005DE61BF8 xsc->flg4=65792 flags=1 2025-09-08 22:00:03.204777*:CAD28028:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-09-08 22:00:03.748783 :CAD2811E: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-09-08 22:00:03.753990*:CAD2812E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301FB6F0 curCtx=0x000000005DE57FD8 xsc->flg4=65792 flags=2 2025-09-08 22:00:03.753990*:CAD2812F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301FB6F0 2025-09-08 22:00:03.763679 :CAD28130: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-09-08 22:00:03.787489 :CAD28138: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-09-08 22:00:03.789903 :CAD28140:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-09-08 22:00:03.790185 :CAD28141:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-09-08 22:00:03.791196 :CAD28142: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-09-08 22:00:03.791314 :CAD28145: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-09-08 22:00:03.792392 :CAD28157: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-09-08 22:00:03.794309 :CAD28170: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-09-08 22:00:03.794728 :CAD28178: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-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-08 22:00:03.863367*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-08 22:00:03.949448 :CAD28234: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-09-08 22:00:05.858748 :CAD2835B: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-09-08 22:00:05.858765 :CAD2835C:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.004.0017be8f 2025-09-08 22:00:08.311204 :CAD284DF: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-09-08 22:00:08.312607 :CAD284E9: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-09-08 22:00:08.323288 :CAD284F5: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-09-08 22:00:08.352593 :CAD28509: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-09-08 22:00:09.536826 :CAD28620:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-09-08 22:00:09.550578 :CAD28623:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:09.551310 :CAD2862B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:00:09.771088 :CAD286A4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:00:09.762 2025-09-08 22:00:09.771090 :CAD286A5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19388 pso_num=29 pso_serial#=157 2025-09-08 22:00:09.771231 :CAD286A6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19388 (legacy spawn) 2025-09-08 22:00:09.780970 :CAD286AB: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-09-08 22:00:09.781142 :CAD286B0: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-09-08 22:00:09.782082 :CAD286B5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:00:23.956151 :CAD28B88:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:00:23.950 2025-09-08 22:00:23.956154 :CAD28B89:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23488 pso_num=29 pso_serial#=158 2025-09-08 22:00:23.956303 :CAD28B8A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23488 osp_idx=27 osp_ver=17242 osp_pg=0 (spawn #572498) 2025-09-08 22:00:23.956304 :CAD28B8B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:00:23.956305 :CAD28B8C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=212 time=1754475252 2025-09-08 22:00:23.956306 :CAD28B8D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:00:23.956306 :CAD28B8E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:00:23.956307 :CAD28B8F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:00:23.956307 :CAD28B90:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:00:23.956308 :CAD28B91:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:00:23.956956 :CAD28B92: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-09-08 22:00:23.957002 :CAD28B98: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-09-08 22:00:23.961382 :CAD28BAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-08 22:00:23.961434 :CAD28BAC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-08 22:00:23.961715 :CAD28BAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-08 22:00:23.961730 :CAD28BAE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-08 22:00:23.961955 :CAD28BAF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-08 22:00:23.961972 :CAD28BB0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-08 22:00:23.962193 :CAD28BB1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-08 22:00:23.962205 :CAD28BB2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-08 22:00:23.962419 :CAD28BBD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-08 22:00:23.962432 :CAD28BBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-08 22:00:23.962643 :CAD28BBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-09-08 22:00:23.962655 :CAD28BC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-09-08 22:00:23.965424 :CAD28BE5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:00:25.372289 :CAD28CC9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:00:25.372 2025-09-08 22:00:25.372291 :CAD28CCA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21540 pso_num=29 pso_serial#=159 2025-09-08 22:00:25.372424 :CAD28CCB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21540 osp_idx=27 osp_ver=17243 osp_pg=0 (spawn #572501) 2025-09-08 22:00:25.372425 :CAD28CCC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:00:25.372425 :CAD28CCD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=213 time=1754476658 2025-09-08 22:00:25.372426 :CAD28CCE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:00:25.372426 :CAD28CCF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:00:25.372426 :CAD28CD0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:00:25.372427 :CAD28CD1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:00:25.372427 :CAD28CD2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:00:25.372931 :CAD28CD3: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-09-08 22:00:25.372974 :CAD28CD9: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-09-08 22:00:25.380991 :CAD28CE7: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-09-08 22:00:25.382151 :CAD28CEF: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-09-08 22:00:25.382717 :CAD28CF7: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-09-08 22:00:25.383174 :CAD28CFF: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-09-08 22:00:25.383608 :CAD28D07: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-09-08 22:00:25.386065 :CAD28D0F: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-09-08 22:00:25.387153 :CAD28D17: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-09-08 22:00:25.442723 :CAD28D1F: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-09-08 22:00:25.443586 :CAD28D27: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-09-08 22:00:25.443834 :CAD28D2F: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-09-08 22:00:25.444114 :CAD28D37: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-09-08 22:00:25.446013 :CAD28D3F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:00:33.828355 :CAD29128:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:00:33.814 2025-09-08 22:00:33.828357 :CAD29129:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18824 pso_num=29 pso_serial#=160 2025-09-08 22:00:33.828499 :CAD2912A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18824 (legacy spawn) 2025-09-08 22:00:33.835018 :CAD2912B: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-09-08 22:00:33.835194 :CAD29130: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-09-08 22:00:33.836016 :CAD29135:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:00:33.853787 :CAD29138:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:00:33.845 2025-09-08 22:00:33.853789 :CAD29139:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18376 pso_num=29 pso_serial#=161 2025-09-08 22:00:33.853931 :CAD2913A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18376 (legacy spawn) 2025-09-08 22:00:33.860555 :CAD2913B: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-09-08 22:00:33.860720 :CAD29144: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-09-08 22:00:33.861533 :CAD29145:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:21:44.608920 :CAD48775:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:21:44.596 2025-09-08 22:21:44.608923 :CAD48776:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17160 pso_num=29 pso_serial#=162 2025-09-08 22:21:44.609062 :CAD48777:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17160 (legacy spawn) 2025-09-08 22:21:44.615640 :CAD48778: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-09-08 22:21:44.616203 :CAD48781: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-09-08 22:21:44.617054 :CAD48782:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:21:44.625875 :CAD48787:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:21:44.611 2025-09-08 22:21:44.625877 :CAD48788:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23372 pso_num=29 pso_serial#=163 2025-09-08 22:21:44.626018 :CAD48789:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23372 (legacy spawn) 2025-09-08 22:21:44.632550 :CAD4878A: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-09-08 22:21:44.632586 :CAD4878D: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-09-08 22:21:44.632908 :CAD48794: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-09-08 22:21:44.633720 :CAD487A1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:21:45.603936 :CAD488F6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:21:45.596 2025-09-08 22:21:45.603939 :CAD488F7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24196 pso_num=29 pso_serial#=164 2025-09-08 22:21:45.604433 :CAD488F8:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=24196 osp_idx=27 osp_ver=17248 osp_pg=0 (spawn #572550) 2025-09-08 22:21:45.604434 :CAD488F9:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:21:45.604434 :CAD488FA:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=214 time=1755756908 2025-09-08 22:21:45.604435 :CAD488FB:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:21:45.604435 :CAD488FC:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:21:45.604436 :CAD488FD:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:21:45.604436 :CAD488FE:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:21:45.604436 :CAD488FF:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:21:45.605311 :CAD48900: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-09-08 22:21:45.605360 :CAD48906: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-09-08 22:21:45.712673 :CAD48AC8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-08 22:21:46.928545 :CAD48B44:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-08 22:21:46.924 2025-09-08 22:21:46.928547 :CAD48B45:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23444 pso_num=29 pso_serial#=165 2025-09-08 22:21:46.928685 :CAD48B46:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23444 osp_idx=27 osp_ver=17249 osp_pg=0 (spawn #572552) 2025-09-08 22:21:46.928685 :CAD48B47:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:21:46.928686 :CAD48B48:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=215 time=1755758221 2025-09-08 22:21:46.928686 :CAD48B49:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:21:46.928687 :CAD48B4A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:21:46.928687 :CAD48B4B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:21:46.928687 :CAD48B4C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:21:46.928688 :CAD48B4D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:21:46.929209 :CAD48B4E: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-09-08 22:21:46.929261 :CAD48B55: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 23444, M002)