Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250623080020\orcl_m001_12000_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:36340M/63366M, Ph+PgF:42568M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 12000, image: ORACLE.EXE (M001) *** 2025-06-23 08:00:20.748 *** SESSION ID:(233.26727) 2025-06-23 08:00:20.748 *** 2025-06-23 08:00:20.748 Process diagnostic dump for ORACLE.EXE (M001), OS id=12000, pid: 29, proc_ser: 64, sid: 233, sess_ser: 26727 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1045 seq_num=1046 snap_id=1 wait times: snap=0.001200 sec, exc=0.001200 sec, total=0.001200 sec wait times: max=infinite, heur=0.056732 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.000009 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1044 seq_num=1045 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.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1043 seq_num=1044 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 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1042 seq_num=1043 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.000006 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1041 seq_num=1042 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.000006 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1040 seq_num=1041 snap_id=1 wait times: snap=0.000041 sec, exc=0.000041 sec, total=0.000041 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=1039 seq_num=1040 snap_id=1 wait times: snap=0.000042 sec, exc=0.000042 sec, total=0.000042 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1038 seq_num=1039 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.000005 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1037 seq_num=1038 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.000006 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1036 seq_num=1037 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.000006 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1035 seq_num=1036 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.000005 sec of elapsed time Sampled Session History of session 233 serial 26727 --------------------------------------------------- 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, 08:00:20] waited for 'ADR block file read', seq_num: 109 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:00:19] idle wait at each sample [session created at: 08:00:20] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:00:20 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-23 08:00:20.748 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 12000, M001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-23 01:00:25.024455 :A85537C1: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-06-23 01:00:25.025268 :A85537C2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 01:00:27.337627 :A8553884:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 01:00:27.332 2025-06-23 01:00:27.337629 :A8553885:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16488 pso_num=29 pso_serial#=55 2025-06-23 01:00:27.337771 :A8553886:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16488 osp_idx=27 osp_ver=13314 osp_pg=0 (spawn #312924) 2025-06-23 01:00:27.337772 :A8553887:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 01:00:27.337772 :A8553888:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=139 time=3616170532 2025-06-23 01:00:27.337772 :A8553889:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 01:00:27.337773 :A855388A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 01:00:27.337773 :A855388B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 01:00:27.337773 :A855388C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 01:00:27.337774 :A855388D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 01:00:27.338263 :A855388E: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-06-23 01:00:27.338310 :A8553894: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-06-23 01:00:27.342406 :A85538A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:00:27.342454 :A85538A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:00:27.342720 :A85538A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:00:27.342736 :A85538AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:00:27.342959 :A85538AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:00:27.342972 :A85538AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:00:27.343181 :A85538AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:00:27.343193 :A85538B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:00:27.343411 :A85538B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:00:27.343425 :A85538BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:00:27.343623 :A85538BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:00:27.343636 :A85538BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:00:27.346347 :A85538D9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 01:00:28.724216 :A85539F4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 01:00:28.724 2025-06-23 01:00:28.724218 :A85539F5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17176 pso_num=29 pso_serial#=56 2025-06-23 01:00:28.724351 :A85539F6:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17176 osp_idx=27 osp_ver=13315 osp_pg=0 (spawn #312927) 2025-06-23 01:00:28.724351 :A85539F7:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 01:00:28.724352 :A85539F8:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=140 time=3616171907 2025-06-23 01:00:28.724352 :A85539F9:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 01:00:28.724353 :A85539FA:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 01:00:28.724353 :A85539FB:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 01:00:28.724354 :A85539FC:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 01:00:28.724354 :A85539FD:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 01:00:28.724852 :A85539FE: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-06-23 01:00:28.724895 :A8553A04: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-06-23 01:00:28.732215 :A8553A12: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-06-23 01:00:28.733350 :A8553A1A: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-06-23 01:00:28.733934 :A8553A22: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-06-23 01:00:28.734415 :A8553A2A: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-06-23 01:00:28.734845 :A8553A32: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-06-23 01:00:28.737357 :A8553A3A: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-06-23 01:00:28.738485 :A8553A42: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-06-23 01:00:28.788858 :A8553A4A: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-06-23 01:00:28.789724 :A8553A52: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-06-23 01:00:28.789967 :A8553A5A: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-06-23 01:00:28.790246 :A8553A62: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-06-23 01:00:28.792051 :A8553A6A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 01:10:28.575656 :A8560770:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 01:10:28.563 2025-06-23 01:10:28.575659 :A8560771:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16664 pso_num=29 pso_serial#=57 2025-06-23 01:10:28.575848 :A8560772:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16664 osp_idx=27 osp_ver=13316 osp_pg=0 (spawn #312950) 2025-06-23 01:10:28.575849 :A8560773:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 01:10:28.575849 :A8560774:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=141 time=3616771766 2025-06-23 01:10:28.575850 :A8560775:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 01:10:28.575851 :A8560776:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 01:10:28.575851 :A8560777:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 01:10:28.575852 :A8560778:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 01:10:28.575852 :A8560779:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 01:10:28.576362 :A856077A: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-06-23 01:10:28.576402 :A8560780: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-06-23 01:10:28.580541 :A8560799:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:10:28.580613 :A856079A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:10:28.580882 :A856079B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:10:28.580900 :A856079C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:10:28.581121 :A856079D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:10:28.581134 :A856079E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:10:28.581348 :A856079F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:10:28.581361 :A85607A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:10:28.581576 :A85607A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:10:28.581589 :A85607A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:10:28.581792 :A85607A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:10:28.581805 :A85607A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:10:28.584155 :A85607A5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 01:40:32.293725 :A8586711:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 01:40:32.281 2025-06-23 01:40:32.293727 :A8586712:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14216 pso_num=29 pso_serial#=58 2025-06-23 01:40:32.293876 :A8586713:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14216 osp_idx=27 osp_ver=13317 osp_pg=0 (spawn #313017) 2025-06-23 01:40:32.293877 :A8586714:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 01:40:32.293877 :A8586715:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=142 time=3618575469 2025-06-23 01:40:32.293877 :A8586716:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 01:40:32.293877 :A8586717:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 01:40:32.293878 :A8586718:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 01:40:32.293878 :A8586719:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 01:40:32.293879 :A858671A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 01:40:32.294375 :A858671B: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-06-23 01:40:32.294417 :A8586721: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-06-23 01:40:32.298503 :A858674A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:40:32.298547 :A858674B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.298802 :A858674C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:40:32.298818 :A858674D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.299034 :A858674E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:40:32.299047 :A858674F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.299252 :A8586750:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:40:32.299265 :A8586751:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.299471 :A8586752:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:40:32.299483 :A8586753:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.299684 :A8586754:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-23 01:40:32.299696 :A8586755:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.302078 :A8586756:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 02:00:34.724020 :A859FED0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 02:00:34.715 2025-06-23 02:00:34.724021 :A859FED1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18364 pso_num=29 pso_serial#=59 2025-06-23 02:00:34.724155 :A859FED2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18364 osp_idx=27 osp_ver=13318 osp_pg=0 (spawn #313065) 2025-06-23 02:00:34.724156 :A859FED3:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 02:00:34.724157 :A859FED4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=143 time=3619777907 2025-06-23 02:00:34.724157 :A859FED5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 02:00:34.724157 :A859FED6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 02:00:34.724158 :A859FED7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 02:00:34.724158 :A859FED8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 02:00:34.724160 :A859FED9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 02:00:34.724665 :A859FEDA: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-06-23 02:00:34.724708 :A859FEE0: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-06-23 02:00:34.733129 :A859FF02:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 03:00:42.116780 :A85EBFA5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 03:00:42.114 2025-06-23 03:00:42.116781 :A85EBFA6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17680 pso_num=29 pso_serial#=60 2025-06-23 03:00:42.116910 :A85EBFA7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17680 osp_idx=27 osp_ver=13319 osp_pg=0 (spawn #313204) 2025-06-23 03:00:42.116911 :A85EBFA8:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 03:00:42.116912 :A85EBFA9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=144 time=3623385297 2025-06-23 03:00:42.116912 :A85EBFAA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 03:00:42.116913 :A85EBFAB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 03:00:42.116913 :A85EBFAC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 03:00:42.116914 :A85EBFAD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 03:00:42.116914 :A85EBFAE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 03:00:42.117394 :A85EBFAF: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-06-23 03:00:42.117439 :A85EBFB6: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-06-23 03:00:42.125499 :A85EBFD7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 04:00:49.740443 :A8636CB5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 04:00:49.738 2025-06-23 04:00:49.740444 :A8636CB6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17360 pso_num=29 pso_serial#=61 2025-06-23 04:00:49.740576 :A8636CB7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17360 osp_idx=27 osp_ver=13320 osp_pg=0 (spawn #313344) 2025-06-23 04:00:49.740577 :A8636CB8:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 04:00:49.740578 :A8636CB9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=145 time=3626992922 2025-06-23 04:00:49.740578 :A8636CBA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 04:00:49.740579 :A8636CBB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 04:00:49.740579 :A8636CBC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 04:00:49.740579 :A8636CBD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 04:00:49.740579 :A8636CBE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 04:00:49.741150 :A8636CBF: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-06-23 04:00:49.741189 :A8636CC5: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-06-23 04:00:49.749373 :A8636CD7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 05:00:57.859124 :A8682967:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 05:00:57.855 2025-06-23 05:00:57.859125 :A8682968:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15256 pso_num=29 pso_serial#=62 2025-06-23 05:00:57.859271 :A8682969:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15256 osp_idx=27 osp_ver=13321 osp_pg=0 (spawn #313486) 2025-06-23 05:00:57.859272 :A868296A:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 05:00:57.859272 :A868296B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=146 time=3630601032 2025-06-23 05:00:57.859272 :A868296C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 05:00:57.859273 :A868296D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 05:00:57.859273 :A868296E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 05:00:57.859273 :A868296F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 05:00:57.859274 :A8682970:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 05:00:57.859814 :A8682971: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-06-23 05:00:57.859859 :A8682977: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-06-23 05:00:57.868197 :A8682989:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 08:00:18.359234 :A8769000:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 08:00:18.352 2025-06-23 08:00:18.359236 :A8769001:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14632 pso_num=29 pso_serial#=63 2025-06-23 08:00:18.360791 :A8769002:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14632 osp_idx=27 osp_ver=13322 osp_pg=0 (spawn #313900) 2025-06-23 08:00:18.360792 :A8769003:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 08:00:18.360793 :A8769004:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=147 time=3641361516 2025-06-23 08:00:18.360793 :A8769005:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 08:00:18.360794 :A8769006:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 08:00:18.360794 :A8769007:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 08:00:18.360794 :A8769008:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 08:00:18.360795 :A8769009:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 08:00:18.363096 :A876900A: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-06-23 08:00:18.363145 :A8769010: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-06-23 08:00:18.363557 :A8769011:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 08:00:20.621382 :A8769157:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 08:00:20.607 2025-06-23 08:00:20.621384 :A8769158:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12000 pso_num=29 pso_serial#=64 2025-06-23 08:00:20.621523 :A8769159:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12000 osp_idx=27 osp_ver=13323 osp_pg=0 (spawn #313902) 2025-06-23 08:00:20.621524 :A876915A:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 08:00:20.621524 :A876915B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=148 time=3641363797 2025-06-23 08:00:20.621525 :A876915C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 08:00:20.621525 :A876915D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 08:00:20.621525 :A876915E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 08:00:20.621526 :A876915F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 08:00:20.621526 :A8769160:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 08:00:20.622038 :A8769161: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-06-23 08:00:20.622085 :A8769167: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: 12000, M001)