Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706220753\orcl_m002_9440_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:37005M/63366M, Ph+PgF:43018M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9440, image: ORACLE.EXE (M002) *** 2025-07-06 22:07:53.933 *** SESSION ID:(193.36361) 2025-07-06 22:07:53.933 *** 2025-07-06 22:07:53.933 Process diagnostic dump for ORACLE.EXE (M002), OS id=9440, pid: 24, proc_ser: 102, sid: 193, sess_ser: 36361 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=344 seq_num=345 snap_id=1 wait times: snap=0.001390 sec, exc=0.001390 sec, total=0.001390 sec wait times: max=infinite, heur=0.123455 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.000020 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=343 seq_num=344 snap_id=1 wait times: snap=0.004604 sec, exc=0.004604 sec, total=0.004604 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=342 seq_num=343 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=341 seq_num=342 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.000009 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=340 seq_num=341 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=339 seq_num=340 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=338 seq_num=339 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.000007 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=337 seq_num=338 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=336 seq_num=337 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=335 seq_num=336 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=334 seq_num=335 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 193 serial 36361 --------------------------------------------------- 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:07:53] idle wait at each sample [session created at: 22:07:53] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 22:07:53.933 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9440, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 22:02:08.884031 :AE7B996E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 22:02:08.886022 :AE7B997A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:02:08.892971 :AE7B999C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:02:08.893 2025-07-06 22:02:08.892973 :AE7B999D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14660 pso_num=24 pso_serial#=94 2025-07-06 22:02:08.893099 :AE7B999E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14660 osp_idx=22 osp_ver=527954 osp_pg=0 (spawn #359180) 2025-07-06 22:02:08.893100 :AE7B999F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:02:08.893100 :AE7B99A0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=58 time=520104783 2025-07-06 22:02:08.893100 :AE7B99A1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:02:08.893101 :AE7B99A2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:02:08.893101 :AE7B99A3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:02:08.893101 :AE7B99A4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:02:08.893102 :AE7B99A5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:02:08.893617 :AE7B99AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.002465 :AE7B9CBB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 22:02:09.007790 :AE7B9CD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.007846 :AE7B9CD6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-06 22:02:09.012136 :AE7B9CE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.120302 :AE7B9CEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.248827 :AE7B9D02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.376264 :AE7B9D7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.492635 :AE7B9DB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.493815 :AE7B9DBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.495750 :AE7B9DC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.497618 :AE7B9DCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.501383 :AE7B9DD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.503164 :AE7B9DDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.519265 :AE7B9DE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.519904 :AE7B9DEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.520414 :AE7B9DF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.521074 :AE7B9DFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.524008 :AE7B9E03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.525070 :AE7B9E0B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.525954 :AE7B9E0C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.526814 :AE7B9E0D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.527275 :AE7B9E0E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.527282 :AE7B9E0F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.527911 :AE7B9E10:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.528515 :AE7B9E11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.528844 :AE7B9E12:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.530049 :AE7B9E13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.569366 :AE7B9E14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.569415 :AE7B9E17:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.570270 :AE7B9E1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.571119 :AE7B9E25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.571586 :AE7B9E26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.579828*:AE7B9E2E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB46E00 estart=1751810529 eid=16778350 ctx=0x000000077B42EF30 cwh=0x0000000029267F80 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-06 22:02:10.579828*:AE7B9E30:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB46E00 curCtx=0x000000002DB45C48 pubTabIdxCnt=0 2025-07-06 22:02:10.579828*:AE7B9E31:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB46E00 2025-07-06 22:02:10.579828*:AE7B9E32:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB46E00 2025-07-06 22:02:10.579828*:AE7B9E35:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002934C120 estart=1751810529 eid=16778350 ctx=0x000000077B447BE8 cwh=0x00000000232A4C90 cpu=15625 buffg=390 interb=0 r=0 rb=0 w=0 wb=0 2025-07-06 22:02:10.579828*:AE7B9E37:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002934C120 curCtx=0x000000002DAFF950 pubTabIdxCnt=0 2025-07-06 22:02:10.579828*:AE7B9E38:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002934C120 2025-07-06 22:02:10.579828*:AE7B9E3A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002934C120 2025-07-06 22:02:10.579828*:AE7B9E3D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000232AEBF0 estart=1751810529 eid=16778350 ctx=0x000000077B458BE8 cwh=0x00000000232A4BA0 cpu=15625 buffg=809 interb=32768 r=4 rb=32768 w=0 wb=0 2025-07-06 22:02:10.579828*:AE7B9E3E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000232AEBF0 curCtx=0x000000002DB3A508 pubTabIdxCnt=0 2025-07-06 22:02:10.579828*:AE7B9E3F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000232AEBF0 2025-07-06 22:02:10.579828*:AE7B9E40:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000232AEBF0 2025-07-06 22:02:10.579828*:AE7B9E43:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB46E00 curCtx=0x000000002DB45C48 xsc->flg4=65792 flags=1 2025-07-06 22:02:10.579828*:AE7B9E44:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB46E00 2025-07-06 22:02:10.579828*:AE7B9E45:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002934C120 curCtx=0x000000002DAFF950 xsc->flg4=65792 flags=1 2025-07-06 22:02:10.579828*:AE7B9E46:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002934C120 2025-07-06 22:02:10.579828*:AE7B9E47:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000232AEBF0 curCtx=0x000000002DB3A508 xsc->flg4=65792 flags=2 2025-07-06 22:02:10.579828*:AE7B9E48:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000232AEBF0 2025-07-06 22:02:10.589595 :AE7B9E49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.589645 :AE7B9E4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.590447 :AE7B9E52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.591334 :AE7B9E5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.591755 :AE7B9E5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.612452 :AE7B9E76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.612501 :AE7B9E7B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.613369 :AE7B9E81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.614181 :AE7B9E89:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.614663 :AE7B9E8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.635494 :AE7B9E9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.635557 :AE7B9E9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.636527 :AE7B9EA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.637510 :AE7B9EAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-06 22:02:10.638155 :AE7B9EAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.660666 :AE7B9EED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.660713 :AE7B9EF0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-06 22:02:10.661658 :AE7B9EF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.662211 :AE7B9EFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.662582 :AE7B9F06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.662763 :AE7B9F0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.662901 :AE7B9F16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.663059 :AE7B9F1E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-06 22:02:10.667138 :AE7B9F1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.667229 :AE7B9F22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-06 22:02:10.667307 :AE7B9F2F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-06 22:02:40.762210 :AE7BAB70:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:03:09.375447 :AE7BB527:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:03:09.364 2025-07-06 22:03:09.375449 :AE7BB528:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13592 pso_num=24 pso_serial#=95 2025-07-06 22:03:09.375577 :AE7BB529:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13592 osp_idx=22 osp_ver=527955 osp_pg=0 (spawn #359185) 2025-07-06 22:03:09.375577 :AE7BB52A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:03:09.375578 :AE7BB52B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=59 time=520165267 2025-07-06 22:03:09.375578 :AE7BB52C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:03:09.375579 :AE7BB52D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:03:09.375579 :AE7BB52E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:03:09.375579 :AE7BB52F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:03:09.375579 :AE7BB530:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:03:09.376064 :AE7BB531:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:03:39.470423 :AE7BBF9A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:04:09.477557 :AE7BC9B7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:04:09.466 2025-07-06 22:04:09.477559 :AE7BC9B8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15568 pso_num=24 pso_serial#=96 2025-07-06 22:04:09.477694 :AE7BC9B9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15568 osp_idx=22 osp_ver=527956 osp_pg=0 (spawn #359187) 2025-07-06 22:04:09.477695 :AE7BC9BA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:04:09.477695 :AE7BC9BB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=60 time=520225361 2025-07-06 22:04:09.477695 :AE7BC9BC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:04:09.477696 :AE7BC9BD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:04:09.477696 :AE7BC9BE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:04:09.477697 :AE7BC9BF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:04:09.477697 :AE7BC9C0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:04:09.478179 :AE7BC9C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:04:39.559518 :AE7BD379:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:05:09.571588 :AE7BDDE0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:05:09.560 2025-07-06 22:05:09.571590 :AE7BDDE1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15556 pso_num=24 pso_serial#=97 2025-07-06 22:05:09.571727 :AE7BDDE2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15556 osp_idx=22 osp_ver=527957 osp_pg=0 (spawn #359189) 2025-07-06 22:05:09.571728 :AE7BDDE3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:05:09.571728 :AE7BDDE4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=61 time=520285454 2025-07-06 22:05:09.571729 :AE7BDDE5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:05:09.571729 :AE7BDDE6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:05:09.571730 :AE7BDDE7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:05:09.571730 :AE7BDDE8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:05:09.571731 :AE7BDDE9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:05:09.572219 :AE7BDDEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:05:34.610033 :AE7BE75E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 22:06:04.704420 :AE7BF6BD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:06:09.669844 :AE7BF87B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:06:09.659 2025-07-06 22:06:09.669846 :AE7BF87C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8164 pso_num=24 pso_serial#=98 2025-07-06 22:06:09.669959 :AE7BF87D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8164 osp_idx=22 osp_ver=527958 osp_pg=0 (spawn #359191) 2025-07-06 22:06:09.669960 :AE7BF87E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:06:09.669960 :AE7BF87F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=62 time=520345564 2025-07-06 22:06:09.669961 :AE7BF880:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:06:09.669961 :AE7BF881:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:06:09.669962 :AE7BF882:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:06:09.669962 :AE7BF883:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:06:09.669962 :AE7BF884:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:06:09.670483 :AE7BF885:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:06:39.760860 :AE7C03ED:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:07:09.765831 :AE7C0E4F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:07:09.753 2025-07-06 22:07:09.765833 :AE7C0E50:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18080 pso_num=24 pso_serial#=99 2025-07-06 22:07:09.765947 :AE7C0E51:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18080 osp_idx=22 osp_ver=527959 osp_pg=0 (spawn #359194) 2025-07-06 22:07:09.765947 :AE7C0E52:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:07:09.765948 :AE7C0E53:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=63 time=520405658 2025-07-06 22:07:09.765948 :AE7C0E54:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:07:09.765948 :AE7C0E55:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:07:09.765949 :AE7C0E56:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:07:09.765949 :AE7C0E57:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:07:09.765949 :AE7C0E58:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:07:09.766532 :AE7C0E59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:07:39.802042 :AE7C19B2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:07:49.250913 :AE7C5394:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:07:49.242 2025-07-06 22:07:49.250914 :AE7C5395:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=580 pso_num=24 pso_serial#=100 2025-07-06 22:07:49.251053 :AE7C5396:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=580 (legacy spawn) 2025-07-06 22:07:49.257797 :AE7C53A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:07:49.258027 :AE7C53AB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-06 22:07:49.258852 :AE7C53AE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:07:52.430238 :AE7C58B3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:07:52.430 2025-07-06 22:07:52.430240 :AE7C58B4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4528 pso_num=24 pso_serial#=101 2025-07-06 22:07:52.430411 :AE7C58B5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4528 osp_idx=22 osp_ver=527961 osp_pg=0 (spawn #359196) 2025-07-06 22:07:52.430411 :AE7C58B6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:07:52.430412 :AE7C58B7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=64 time=520448314 2025-07-06 22:07:52.430412 :AE7C58B8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:07:52.430412 :AE7C58B9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:07:52.430412 :AE7C58BA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:07:52.430412 :AE7C58BB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:07:52.430413 :AE7C58BC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:07:52.431856 :AE7C58BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:07:52.431901 :AE7C58C3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-06 22:07:52.560268 :AE7C58F5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-06 22:07:53.727686 :AE7C5935:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-06 22:07:53.714 2025-07-06 22:07:53.727688 :AE7C5936:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9440 pso_num=24 pso_serial#=102 2025-07-06 22:07:53.727815 :AE7C5937:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9440 osp_idx=22 osp_ver=527962 osp_pg=0 (spawn #359198) 2025-07-06 22:07:53.727816 :AE7C5938:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:07:53.727816 :AE7C5939:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=65 time=520449611 2025-07-06 22:07:53.727817 :AE7C593A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:07:53.727817 :AE7C593B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:07:53.727818 :AE7C593C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:07:53.727818 :AE7C593D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:07:53.727818 :AE7C593E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:07:53.728353 :AE7C593F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:07:53.728396 :AE7C5945:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 9440, M002)