Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250502221904\orcl_m002_9468_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:37589M/63366M, Ph+PgF:45130M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 9468, image: ORACLE.EXE (M002) *** 2025-05-02 22:19:04.452 *** SESSION ID:(233.20317) 2025-05-02 22:19:04.452 *** 2025-05-02 22:19:04.452 Process diagnostic dump for ORACLE.EXE (M002), OS id=9468, pid: 29, proc_ser: 193, sid: 233, sess_ser: 20317 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.028522 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.028548 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x1, filetype=0x2 wait_id=1224 seq_num=1225 snap_id=1 wait times: snap=0.000078 sec, exc=0.000078 sec, total=0.000078 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000666 sec of elapsed time 1: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x0, timeout=0xffffffff wait_id=1223 seq_num=1224 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.000236 sec of elapsed time 2: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x0, timeout=0xffffffff wait_id=1222 seq_num=1223 snap_id=1 wait times: snap=0.000057 sec, exc=0.000057 sec, total=0.000057 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000276 sec of elapsed time 3: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x0, timeout=0xffffffff wait_id=1221 seq_num=1222 snap_id=1 wait times: snap=0.000055 sec, exc=0.000055 sec, total=0.000055 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000262 sec of elapsed time 4: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x0, timeout=0xffffffff wait_id=1220 seq_num=1221 snap_id=1 wait times: snap=0.000058 sec, exc=0.000058 sec, total=0.000058 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000239 sec of elapsed time 5: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x0, timeout=0xffffffff wait_id=1219 seq_num=1220 snap_id=1 wait times: snap=0.000095 sec, exc=0.000095 sec, total=0.000095 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.006588 sec of elapsed time 6: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x100, timeout=0x7fffffff wait_id=1218 seq_num=1219 snap_id=1 wait times: snap=0.000178 sec, exc=0.000178 sec, total=0.000178 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000432 sec of elapsed time 7: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x2, filetype=0x2 wait_id=1217 seq_num=1218 snap_id=1 wait times: snap=0.000291 sec, exc=0.000291 sec, total=0.000291 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000055 sec of elapsed time 8: waited for 'control file sequential read' file#=0x0, block#=0x119, blocks=0x1 wait_id=1216 seq_num=1217 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.000004 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=1215 seq_num=1216 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.000005 sec of elapsed time Sampled Session History of session 233 serial 20317 --------------------------------------------------- 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:19:04] idle wait at each sample [session created at: 22:19:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-02 22:19:04.452 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 9468, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-02 16:00:13.725709 :91BBF3AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.726255 :91BBF3B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.726719 :91BBF3BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.727150 :91BBF3C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.730047 :91BBF3CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.731113 :91BBF3D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.779779 :91BBF3DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.780713 :91BBF3E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.780960 :91BBF3EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.781189 :91BBF3F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 16:00:13.783069 :91BBF3FA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:00:01.239973 :91D83654:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:00:01.229 2025-05-02 22:00:01.239974 :91D83655:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8172 pso_num=29 pso_serial#=187 2025-05-02 22:00:01.240102 :91D8365D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=8172 osp_idx=27 osp_ver=10131 osp_pg=0 (spawn #141928) 2025-05-02 22:00:01.240103 :91D8365E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:00:01.240103 :91D8365F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=232 time=3493990718 2025-05-02 22:00:01.240104 :91D83660:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:00:01.240104 :91D83661:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:00:01.240104 :91D83662:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:00:01.240105 :91D83663:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:00:01.240105 :91D83664:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:00:01.240637 :91D83665: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-05-02 22:00:01.245895 :91D83853:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.245935 :91D83856:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-02 22:00:01.251414 :91D83881:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.458940 :91D83A07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.657849 :91D83A3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:01.789517 :91D83A68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.916916 :91D83C53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.917986 :91D83C5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.922114 :91D83C63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.924476 :91D83C6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.926075 :91D83C73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.929188 :91D83C7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.930836 :91D83C83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.950629 :91D83C8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.951233 :91D83C93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.951741 :91D83C9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.952240 :91D83CA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.954893 :91D83CAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:02.955832 :91D83CB3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:02.956581 :91D83CB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:02.957367 :91D83CB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:02.957859 :91D83CB6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:02.957866 :91D83CB7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:02.958450 :91D83CB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:02.958953 :91D83CB9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:02.959231 :91D83CBA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:02.960347 :91D83CBB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:02.988451*:91D83CCE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000318E06A0 estart=1746194401 eid=16777622 ctx=0x000000077B42EF30 cwh=0x00000000318D0338 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-05-02 22:00:02.988451*:91D83CD0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000318E06A0 curCtx=0x00000000318FF278 pubTabIdxCnt=0 2025-05-02 22:00:02.988451*:91D83CD4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000318E06A0 2025-05-02 22:00:02.988451*:91D83CD5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000318E06A0 2025-05-02 22:00:02.988451*:91D83CD6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000318D5398 estart=1746194401 eid=16777622 ctx=0x000000077B447BE8 cwh=0x00000000319370F8 cpu=15625 buffg=551 interb=0 r=0 rb=0 w=0 wb=0 2025-05-02 22:00:02.988451*:91D83CD7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000318D5398 curCtx=0x00000000318D1B58 pubTabIdxCnt=0 2025-05-02 22:00:02.988451*:91D83CD8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000318D5398 2025-05-02 22:00:02.988451*:91D83CD9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000318D5398 2025-05-02 22:00:02.988451*:91D83CDA:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC32440 estart=1746194401 eid=16777622 ctx=0x000000077B458BE8 cwh=0x000000002DBEBC10 cpu=15625 buffg=974 interb=98304 r=12 rb=98304 w=0 wb=0 2025-05-02 22:00:02.988451*:91D83CDB:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC32440 curCtx=0x000000003199A508 pubTabIdxCnt=0 2025-05-02 22:00:02.988451*:91D83CDC:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC32440 2025-05-02 22:00:02.988451*:91D83CDD:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC32440 2025-05-02 22:00:02.988451*:91D83D0A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000318E06A0 curCtx=0x00000000318FF278 xsc->flg4=65792 flags=1 2025-05-02 22:00:02.988451*:91D83D0B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000318E06A0 2025-05-02 22:00:02.988451*:91D83D0C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000318D5398 curCtx=0x00000000318D1B58 xsc->flg4=65792 flags=1 2025-05-02 22:00:02.988451*:91D83D0D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000318D5398 2025-05-02 22:00:02.988451*:91D83D0E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC32440 curCtx=0x000000003199A508 xsc->flg4=65792 flags=2 2025-05-02 22:00:02.988451*:91D83D0F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC32440 2025-05-02 22:00:03.002809 :91D83D10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.002859 :91D83D13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:03.003772 :91D83D19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.004695 :91D83D21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:03.005149 :91D83D22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.025424 :91D83D2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.025479 :91D83D2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:03.026352 :91D83D33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.027163 :91D83D3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:03.027639 :91D83D3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.045335 :91D83D44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.045386 :91D83D47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:03.046279 :91D83D4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.057980 :91D83D55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:03.058575 :91D83D56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.078866 :91D83D63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.078914 :91D83D6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:03.079879 :91D83D76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.080723 :91D83D7E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:03.081150 :91D83D7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.102931 :91D83D99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:03.103745 :91D83DAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.104586 :91D83DBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-02 22:00:03.105042 :91D83DBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.125696 :91D83DD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.125748 :91D83DDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-02 22:00:03.126591 :91D83DE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.126986 :91D83DE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.127320 :91D83DF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.127565 :91D83DF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.127743 :91D83E00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.127870 :91D83E08:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-05-02 22:00:03.131628 :91D83E09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:00:03.131737 :91D83E0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-02 22:00:33.250948 :91D84B13:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:01:00.139262 :91D85644:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:01:00.128 2025-05-02 22:01:00.139264 :91D85645:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10484 pso_num=29 pso_serial#=188 2025-05-02 22:01:00.139392 :91D85646:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10484 osp_idx=27 osp_ver=10132 osp_pg=0 (spawn #141936) 2025-05-02 22:01:00.139393 :91D85647:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:01:00.139393 :91D85648:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=233 time=3494049609 2025-05-02 22:01:00.139394 :91D85649:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:01:00.139394 :91D8564A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:01:00.139394 :91D8564B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:01:00.139395 :91D8564C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:01:00.139395 :91D8564D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:01:00.139927 :91D8564E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:01:00.139973 :91D85654:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 22:01:00.147957 :91D85666:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:01:01.512757 :91D85743:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:01:01.503 2025-05-02 22:01:01.512758 :91D85744:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11244 pso_num=29 pso_serial#=189 2025-05-02 22:01:01.512895 :91D85745:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11244 osp_idx=27 osp_ver=10133 osp_pg=0 (spawn #141937) 2025-05-02 22:01:01.512895 :91D85746:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:01:01.512896 :91D85747:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=234 time=3494050984 2025-05-02 22:01:01.512896 :91D85748:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:01:01.512896 :91D85749:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:01:01.512897 :91D8574A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:01:01.512897 :91D8574B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:01:01.512897 :91D8574C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:01:01.513394 :91D8574D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.513440 :91D85753:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 22:01:01.521080 :91D85761:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.522170 :91D85769:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.522651 :91D85771:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.523089 :91D85779:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.523593 :91D85781:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.525997 :91D85789:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.527048 :91D85791:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.582187 :91D857A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.583073 :91D857AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.583312 :91D857B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.583536 :91D857BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:01:01.585292 :91D857C5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:01:06.207072 :91D8598A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:01:06.209 2025-05-02 22:01:06.207075 :91D8598B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7588 pso_num=29 pso_serial#=190 2025-05-02 22:01:06.207209 :91D8598C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7588 osp_idx=27 osp_ver=10134 osp_pg=0 (spawn #141939) 2025-05-02 22:01:06.207210 :91D8598D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:01:06.207210 :91D8598E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=235 time=3494055687 2025-05-02 22:01:06.207210 :91D8598F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:01:06.207211 :91D85990:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:01:06.207211 :91D85991:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:01:06.207212 :91D85992:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:01:06.207212 :91D85993:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:01:06.207723 :91D85994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:01:06.207766 :91D8599C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 22:01:06.214379 :91D859A7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:19:03.024326 :91D9D770:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:19:03.016 2025-05-02 22:19:03.024328 :91D9D771:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11128 pso_num=29 pso_serial#=191 2025-05-02 22:19:03.024482 :91D9D772:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11128 osp_idx=27 osp_ver=10135 osp_pg=0 (spawn #141981) 2025-05-02 22:19:03.024482 :91D9D773:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:19:03.024483 :91D9D774:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=236 time=3495132500 2025-05-02 22:19:03.024483 :91D9D775:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:19:03.024483 :91D9D776:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:19:03.024484 :91D9D777:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:19:03.024484 :91D9D778:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:19:03.024484 :91D9D779:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:19:03.025343 :91D9D77A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:19:03.025386 :91D9D780:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 22:19:03.151629 :91D9D7B2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:19:03.556269 :91D9D7B5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:19:03.548 2025-05-02 22:19:03.556271 :91D9D7B6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11272 pso_num=29 pso_serial#=192 2025-05-02 22:19:03.556400 :91D9D7B7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11272 (legacy spawn) 2025-05-02 22:19:03.562793 :91D9D7B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-02 22:19:03.562965 :91D9D7BD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-02 22:19:03.563746 :91D9D7C2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-02 22:19:04.259499 :91D9D832:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-02 22:19:04.249 2025-05-02 22:19:04.259501 :91D9D833:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9468 pso_num=29 pso_serial#=193 2025-05-02 22:19:04.259629 :91D9D834:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9468 osp_idx=27 osp_ver=10137 osp_pg=0 (spawn #141983) 2025-05-02 22:19:04.259630 :91D9D835:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 22:19:04.259630 :91D9D836:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=237 time=3495133734 2025-05-02 22:19:04.259631 :91D9D837:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:19:04.259631 :91D9D838:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:19:04.259632 :91D9D839:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:19:04.259632 :91D9D83A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:19:04.259632 :91D9D83B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:19:04.260130 :91D9D83C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 22:19:04.260174 :91D9D842: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: 9468, M002)