Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250603081945\orcl_m002_14720_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:37028M/63366M, Ph+PgF:43858M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 14720, image: ORACLE.EXE (M002) *** 2025-06-03 08:19:45.789 *** SESSION ID:(225.21579) 2025-06-03 08:19:45.789 *** 2025-06-03 08:19:45.789 Process diagnostic dump for ORACLE.EXE (M002), OS id=14720, pid: 28, proc_ser: 31, sid: 225, sess_ser: 21579 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=591 seq_num=592 snap_id=1 wait times: snap=0.008139 sec, exc=0.008139 sec, total=0.008139 sec wait times: max=infinite, heur=0.009182 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.000005 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=590 seq_num=591 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=589 seq_num=590 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 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=588 seq_num=589 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.000006 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=587 seq_num=588 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 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=586 seq_num=587 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.000005 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=585 seq_num=586 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.000005 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=584 seq_num=585 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.000008 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=583 seq_num=584 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.000007 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=582 seq_num=583 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.000007 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=581 seq_num=582 snap_id=1 wait times: snap=0.000054 sec, exc=0.000054 sec, total=0.000054 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time Sampled Session History of session 225 serial 21579 --------------------------------------------------- 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:19:45] waited for 'ADR block file read', seq_num: 378 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:19:44] idle wait at each sample [session created at: 08:19:44] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:19:45 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-03 08:19:45.789 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 14720, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-03 05:42:26.855471 :9F9F60F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 05:42:26.857787 :9F9F60F7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 06:00:30.470823 :9FA0CA5C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 06:00:30.469 2025-06-03 06:00:30.470824 :9FA0CA5D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10780 pso_num=28 pso_serial#=20 2025-06-03 06:00:30.470956 :9FA0CA5E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10780 osp_idx=26 osp_ver=174488 osp_pg=0 (spawn #246936) 2025-06-03 06:00:30.470956 :9FA0CA5F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 06:00:30.470957 :9FA0CA60:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=133 time=1906189110 2025-06-03 06:00:30.470957 :9FA0CA61:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 06:00:30.470957 :9FA0CA62:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 06:00:30.470958 :9FA0CA63:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 06:00:30.470958 :9FA0CA64:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 06:00:30.470958 :9FA0CA65:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 06:00:30.471443 :9FA0CA66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.471486 :9FA0CA6C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 06:00:30.478701 :9FA0CA7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.479815 :9FA0CA82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.480324 :9FA0CA8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.480766 :9FA0CA92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.481191 :9FA0CA9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.483670 :9FA0CAA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.484752 :9FA0CAAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.534607 :9FA0CAB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.535584 :9FA0CABA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.535829 :9FA0CAC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.536043 :9FA0CACA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:00:30.537763 :9FA0CAD2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 06:02:29.372084 :9FA0F205:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 06:02:29.360 2025-06-03 06:02:29.372086 :9FA0F206:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15648 pso_num=28 pso_serial#=21 2025-06-03 06:02:29.372287 :9FA0F207:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15648 osp_idx=26 osp_ver=174489 osp_pg=0 (spawn #246942) 2025-06-03 06:02:29.372287 :9FA0F208:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 06:02:29.372288 :9FA0F209:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=134 time=1906308016 2025-06-03 06:02:29.372288 :9FA0F20A:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 06:02:29.372288 :9FA0F20B:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 06:02:29.372289 :9FA0F20C:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 06:02:29.372289 :9FA0F20D:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 06:02:29.372290 :9FA0F20E:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 06:02:29.372891 :9FA0F20F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 06:02:29.372940 :9FA0F215:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 06:02:29.381371 :9FA0F22A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 06:12:30.559463 :9FA1BE98:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 06:12:30.547 2025-06-03 06:12:30.559465 :9FA1BE99:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14444 pso_num=28 pso_serial#=22 2025-06-03 06:12:30.559595 :9FA1BE9A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14444 osp_idx=26 osp_ver=174490 osp_pg=0 (spawn #246964) 2025-06-03 06:12:30.559596 :9FA1BE9B:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 06:12:30.559596 :9FA1BE9C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=135 time=1906909204 2025-06-03 06:12:30.559596 :9FA1BE9D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 06:12:30.559597 :9FA1BE9E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 06:12:30.559597 :9FA1BE9F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 06:12:30.559597 :9FA1BEA0:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 06:12:30.559598 :9FA1BEA1:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 06:12:30.560102 :9FA1BEA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 06:12:30.560140 :9FA1BEA8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 06:12:30.564260 :9FA1BEC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:12:30.564303 :9FA1BEC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:12:30.564558 :9FA1BEC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:12:30.564575 :9FA1BEC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:12:30.564785 :9FA1BEC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:12:30.564798 :9FA1BEC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:12:30.565012 :9FA1BEC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:12:30.565033 :9FA1BEC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:12:30.565301 :9FA1BEC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:12:30.565314 :9FA1BECA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:12:30.565515 :9FA1BECB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:12:30.565528 :9FA1BECC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:12:30.567767 :9FA1BECD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 06:42:34.596947 :9FA418B4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 06:42:34.584 2025-06-03 06:42:34.596949 :9FA418B5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15636 pso_num=28 pso_serial#=23 2025-06-03 06:42:34.597092 :9FA418B6:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15636 osp_idx=26 osp_ver=174491 osp_pg=0 (spawn #247032) 2025-06-03 06:42:34.597093 :9FA418B7:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 06:42:34.597093 :9FA418B8:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=136 time=1908713235 2025-06-03 06:42:34.597094 :9FA418B9:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 06:42:34.597094 :9FA418BA:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 06:42:34.597095 :9FA418BB:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 06:42:34.597095 :9FA418BC:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 06:42:34.597095 :9FA418BD:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 06:42:34.597630 :9FA418BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 06:42:34.597675 :9FA418C4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 06:42:34.601767 :9FA418DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:42:34.601814 :9FA418DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:42:34.602074 :9FA418DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:42:34.602089 :9FA418E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:42:34.602298 :9FA418E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:42:34.602313 :9FA418E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:42:34.602524 :9FA418E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:42:34.602537 :9FA418E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:42:34.602745 :9FA418E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:42:34.602759 :9FA418E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:42:34.602963 :9FA418E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 06:42:34.602975 :9FA418E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 06:42:34.605376 :9FA418E9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 06:44:28.874934 :9FA43E7F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 06:44:28.862 2025-06-03 06:44:28.874936 :9FA43E80:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15632 pso_num=28 pso_serial#=24 2025-06-03 06:44:28.875077 :9FA43E81:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15632 (legacy spawn) 2025-06-03 06:44:28.882427 :9FA43E8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 06:44:28.882582 :9FA43E98:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 06:44:28.883381 :9FA43E99:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 07:02:37.404065 :9FA5ACDD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 07:02:37.392 2025-06-03 07:02:37.404066 :9FA5ACDE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10512 pso_num=28 pso_serial#=25 2025-06-03 07:02:37.404202 :9FA5ACDF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10512 osp_idx=26 osp_ver=174493 osp_pg=0 (spawn #247081) 2025-06-03 07:02:37.404202 :9FA5ACE0:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 07:02:37.404203 :9FA5ACE1:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=137 time=1909916047 2025-06-03 07:02:37.404204 :9FA5ACE2:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 07:02:37.404205 :9FA5ACE3:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 07:02:37.404205 :9FA5ACE4:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 07:02:37.404206 :9FA5ACE5:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 07:02:37.404206 :9FA5ACE6:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 07:02:37.404779 :9FA5ACE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 07:02:37.404832 :9FA5ACED:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 07:02:37.413230 :9FA5AD02:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 07:12:38.778756 :9FA67AB1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 07:12:38.766 2025-06-03 07:12:38.778758 :9FA67AB2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12084 pso_num=28 pso_serial#=26 2025-06-03 07:12:38.778903 :9FA67AB3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12084 osp_idx=26 osp_ver=174494 osp_pg=0 (spawn #247104) 2025-06-03 07:12:38.778904 :9FA67AB4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 07:12:38.778905 :9FA67AB5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=138 time=1910517422 2025-06-03 07:12:38.778905 :9FA67AB6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 07:12:38.778905 :9FA67AB7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 07:12:38.778906 :9FA67AB8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 07:12:38.778906 :9FA67AB9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 07:12:38.778907 :9FA67ABA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 07:12:38.779395 :9FA67ABB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 07:12:38.779435 :9FA67AC1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 07:12:38.783520 :9FA67ADA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 07:12:38.783567 :9FA67ADB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 07:12:38.783823 :9FA67ADC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 07:12:38.783840 :9FA67ADD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 07:12:38.784047 :9FA67ADE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 07:12:38.784061 :9FA67ADF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 07:12:38.784264 :9FA67AE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 07:12:38.784276 :9FA67AE1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 07:12:38.784482 :9FA67AE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 07:12:38.784495 :9FA67AE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 07:12:38.784696 :9FA67AE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-03 07:12:38.784708 :9FA67AE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-03 07:12:38.786991 :9FA67AE6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 08:19:41.034016 :9FABDEC3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 08:19:41.021 2025-06-03 08:19:41.034018 :9FABDEC4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8164 pso_num=28 pso_serial#=27 2025-06-03 08:19:41.034165 :9FABDEC5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8164 (legacy spawn) 2025-06-03 08:19:41.040632 :9FABDEC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 08:19:41.041002 :9FABDECF:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 08:19:41.041829 :9FABDED0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 08:19:41.050464 :9FABDED3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 08:19:41.037 2025-06-03 08:19:41.050466 :9FABDED4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12664 pso_num=28 pso_serial#=28 2025-06-03 08:19:41.050601 :9FABDED5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12664 (legacy spawn) 2025-06-03 08:19:41.056992 :9FABDED8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 08:19:41.057348 :9FABDEE1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 08:19:41.058158 :9FABDEE2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 08:19:42.769098 :9FABE189:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 08:19:42.755 2025-06-03 08:19:42.769101 :9FABE18A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12148 pso_num=28 pso_serial#=29 2025-06-03 08:19:42.769268 :9FABE18B:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12148 osp_idx=26 osp_ver=174497 osp_pg=0 (spawn #247258) 2025-06-03 08:19:42.769268 :9FABE18C:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 08:19:42.769269 :9FABE18D:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=139 time=1914541407 2025-06-03 08:19:42.769269 :9FABE18E:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 08:19:42.769270 :9FABE18F:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 08:19:42.769270 :9FABE190:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 08:19:42.769271 :9FABE191:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 08:19:42.769271 :9FABE192:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 08:19:42.770201 :9FABE193:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 08:19:42.770245 :9FABE199:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 08:19:42.881026 :9FABE1CB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 08:19:44.530873 :9FABE27B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 08:19:44.523 2025-06-03 08:19:44.530875 :9FABE27C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11632 pso_num=28 pso_serial#=30 2025-06-03 08:19:44.531002 :9FABE27D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11632 (legacy spawn) 2025-06-03 08:19:44.537341 :9FABE27E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-03 08:19:44.537511 :9FABE286:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-03 08:19:44.538270 :9FABE287:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-03 08:19:44.987269 :9FABE2D7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-03 08:19:44.976 2025-06-03 08:19:44.987270 :9FABE2D8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14720 pso_num=28 pso_serial#=31 2025-06-03 08:19:44.987403 :9FABE2D9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14720 osp_idx=26 osp_ver=174499 osp_pg=0 (spawn #247260) 2025-06-03 08:19:44.987404 :9FABE2DA:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 08:19:44.987404 :9FABE2DB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=140 time=1914543625 2025-06-03 08:19:44.987405 :9FABE2DC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 08:19:44.987406 :9FABE2DD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 08:19:44.987406 :9FABE2DE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 08:19:44.987406 :9FABE2DF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 08:19:44.987407 :9FABE2E0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 08:19:44.987905 :9FABE2E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-03 08:19:44.987951 :9FABE2E7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 14720, M002)