Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250509082424\orcl_m002_10664_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:37507M/63366M, Ph+PgF:44908M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 10664, image: ORACLE.EXE (M002) *** 2025-05-09 08:24:24.911 *** SESSION ID:(225.58905) 2025-05-09 08:24:24.911 *** 2025-05-09 08:24:24.911 Process diagnostic dump for ORACLE.EXE (M002), OS id=10664, pid: 28, proc_ser: 16, sid: 225, sess_ser: 58905 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1075 seq_num=1076 snap_id=1 wait times: snap=0.011173 sec, exc=0.011173 sec, total=0.011173 sec wait times: max=infinite, heur=0.047184 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=1074 seq_num=1075 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.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1073 seq_num=1074 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 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1072 seq_num=1073 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 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1071 seq_num=1072 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 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1070 seq_num=1071 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 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1069 seq_num=1070 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1068 seq_num=1069 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.000006 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1067 seq_num=1068 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 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1066 seq_num=1067 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1065 seq_num=1066 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.000006 sec of elapsed time Sampled Session History of session 225 serial 58905 --------------------------------------------------- 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:24:24] idle wait at each sample [session created at: 08:24:24] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-09 08:24:24.911 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 10664, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-09 06:01:23.109380 :94A8B40D: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-05-09 06:01:23.115986 :94A8B419:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 06:01:26.120568 :94A8B560:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 06:01:26.108 2025-05-09 06:01:26.120571 :94A8B561:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10152 pso_num=28 pso_serial#=7 2025-05-09 06:01:26.120712 :94A8B562:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10152 osp_idx=26 osp_ver=94866 osp_pg=0 (spawn #163415) 2025-05-09 06:01:26.120713 :94A8B563:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 06:01:26.120713 :94A8B564:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=142 time=4041263281 2025-05-09 06:01:26.120714 :94A8B565:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 06:01:26.120714 :94A8B566:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 06:01:26.120714 :94A8B567:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 06:01:26.120715 :94A8B568:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 06:01:26.120715 :94A8B569:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 06:01:26.121234 :94A8B56A: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-05-09 06:01:26.121275 :94A8B570: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-05-09 06:01:26.129065 :94A8B582:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 06:16:28.073062 :94A9E79E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 06:16:28.071 2025-05-09 06:16:28.073063 :94A9E79F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7880 pso_num=28 pso_serial#=8 2025-05-09 06:16:28.073152 :94A9E7A0:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=7880 osp_idx=26 osp_ver=94867 osp_pg=0 (spawn #163449) 2025-05-09 06:16:28.073152 :94A9E7A1:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 06:16:28.073153 :94A9E7A2:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=143 time=4042165234 2025-05-09 06:16:28.073153 :94A9E7A3:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 06:16:28.073153 :94A9E7A4:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 06:16:28.073153 :94A9E7A5:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 06:16:28.073153 :94A9E7A6:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 06:16:28.073153 :94A9E7A7:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 06:16:28.073480 :94A9E7A8: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-05-09 06:16:28.073520 :94A9E7AE: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-05-09 06:16:28.077364 :94A9E7C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:16:28.077405 :94A9E7C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:16:28.077671 :94A9E7C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:16:28.077687 :94A9E7CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:16:28.077903 :94A9E7CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:16:28.077917 :94A9E7CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:16:28.078118 :94A9E7CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:16:28.078130 :94A9E7CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:16:28.078362 :94A9E7CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:16:28.078384 :94A9E7D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:16:28.078652 :94A9E7D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:16:28.078665 :94A9E7D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:16:28.081144 :94A9E7D3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 06:46:32.392645 :94AC3AFC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 06:46:32.380 2025-05-09 06:46:32.392648 :94AC3AFD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11956 pso_num=28 pso_serial#=9 2025-05-09 06:46:32.392819 :94AC3AFE:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11956 osp_idx=26 osp_ver=94868 osp_pg=0 (spawn #163517) 2025-05-09 06:46:32.392820 :94AC3AFF:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 06:46:32.392820 :94AC3B00:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=144 time=4043969546 2025-05-09 06:46:32.392821 :94AC3B01:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 06:46:32.392821 :94AC3B02:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 06:46:32.392821 :94AC3B03:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 06:46:32.392822 :94AC3B04:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 06:46:32.392822 :94AC3B05:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 06:46:32.393366 :94AC3B06: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-05-09 06:46:32.393406 :94AC3B0C: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-05-09 06:46:32.397421 :94AC3B25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:46:32.397469 :94AC3B26:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:46:32.397721 :94AC3B27:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:46:32.397737 :94AC3B28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:46:32.397950 :94AC3B29:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:46:32.397963 :94AC3B2A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:46:32.398167 :94AC3B2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:46:32.398179 :94AC3B2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:46:32.398388 :94AC3B2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:46:32.398401 :94AC3B2E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:46:32.398601 :94AC3B2F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 06:46:32.398614 :94AC3B30:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 06:46:32.400927 :94AC3B31:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 07:00:35.747287 :94AD52A6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 07:00:35.745 2025-05-09 07:00:35.747289 :94AD52A7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10760 pso_num=28 pso_serial#=10 2025-05-09 07:00:35.747421 :94AD52A8:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10760 osp_idx=26 osp_ver=94869 osp_pg=0 (spawn #163550) 2025-05-09 07:00:35.747422 :94AD52A9:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:00:35.747422 :94AD52AA:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=145 time=4044812906 2025-05-09 07:00:35.747422 :94AD52AB:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:00:35.747423 :94AD52AC:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:00:35.747423 :94AD52AD:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:00:35.747424 :94AD52AE:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:00:35.747424 :94AD52AF:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:00:35.747931 :94AD52B0: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-05-09 07:00:35.747990 :94AD52B6: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-05-09 07:00:35.755085 :94AD52C4: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-05-09 07:00:35.756141 :94AD52CC: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-05-09 07:00:35.756626 :94AD52D4: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-05-09 07:00:35.757066 :94AD52DC: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-05-09 07:00:35.757489 :94AD52E4: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-05-09 07:00:35.760027 :94AD52EC: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-05-09 07:00:35.761154 :94AD52F4: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-05-09 07:00:35.810922 :94AD52FC: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-05-09 07:00:35.811778 :94AD5304: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-05-09 07:00:35.812092 :94AD530C: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-05-09 07:00:35.812333 :94AD5314: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-05-09 07:00:35.813985 :94AD531C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 07:01:25.497631 :94AD63DC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 07:01:25.492 2025-05-09 07:01:25.497633 :94AD63DD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9028 pso_num=28 pso_serial#=11 2025-05-09 07:01:25.497766 :94AD63DE:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=9028 osp_idx=26 osp_ver=94870 osp_pg=0 (spawn #163554) 2025-05-09 07:01:25.497766 :94AD63DF:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:01:25.497767 :94AD63E0:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=146 time=4044862656 2025-05-09 07:01:25.497767 :94AD63E1:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:01:25.497767 :94AD63E2:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:01:25.497768 :94AD63E3:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:01:25.497768 :94AD63E4:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:01:25.497770 :94AD63E5:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:01:25.498251 :94AD63E6: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-05-09 07:01:25.498296 :94AD63EE: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-05-09 07:01:25.504603 :94AD63FA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 07:01:34.525779 :94AD671E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 07:01:34.517 2025-05-09 07:01:34.525781 :94AD671F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8696 pso_num=28 pso_serial#=12 2025-05-09 07:01:34.525913 :94AD6720:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=8696 osp_idx=26 osp_ver=94871 osp_pg=0 (spawn #163556) 2025-05-09 07:01:34.525914 :94AD6721:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:01:34.525914 :94AD6722:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=147 time=4044871687 2025-05-09 07:01:34.525915 :94AD6723:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:01:34.525915 :94AD6724:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:01:34.525916 :94AD6725:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:01:34.525916 :94AD6726:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:01:34.525916 :94AD6727:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:01:34.526460 :94AD6728: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-05-09 07:01:34.526504 :94AD672E: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-05-09 07:01:34.534345 :94AD6740:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 07:16:36.672549 :94AE97C5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 07:16:36.660 2025-05-09 07:16:36.672551 :94AE97C6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13076 pso_num=28 pso_serial#=13 2025-05-09 07:16:36.672685 :94AE97C7:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13076 osp_idx=26 osp_ver=94872 osp_pg=0 (spawn #163590) 2025-05-09 07:16:36.672687 :94AE97C8:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 07:16:36.672688 :94AE97C9:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=148 time=4045773828 2025-05-09 07:16:36.672689 :94AE97CA:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 07:16:36.672689 :94AE97CB:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 07:16:36.672689 :94AE97CC:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 07:16:36.672690 :94AE97CD:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 07:16:36.672690 :94AE97CE:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 07:16:36.673194 :94AE97CF: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-05-09 07:16:36.673233 :94AE97D5: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-05-09 07:16:36.677425 :94AE97EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 07:16:36.677467 :94AE97EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 07:16:36.677720 :94AE97F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 07:16:36.677736 :94AE97F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 07:16:36.677983 :94AE97F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 07:16:36.678003 :94AE97F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 07:16:36.678237 :94AE97F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 07:16:36.678251 :94AE97F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 07:16:36.678463 :94AE97F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 07:16:36.678476 :94AE97F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 07:16:36.678686 :94AE97F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-09 07:16:36.678699 :94AE97F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-09 07:16:36.681017 :94AE97FA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 08:01:27.771567 :94B21102:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 08:01:27.766 2025-05-09 08:01:27.771570 :94B21103:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11928 pso_num=28 pso_serial#=14 2025-05-09 08:01:27.771697 :94B21104:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11928 osp_idx=26 osp_ver=94873 osp_pg=0 (spawn #163694) 2025-05-09 08:01:27.771697 :94B21105:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 08:01:27.771698 :94B21106:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=149 time=4048464937 2025-05-09 08:01:27.771698 :94B21107:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 08:01:27.771699 :94B21108:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 08:01:27.771699 :94B21109:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 08:01:27.771699 :94B2110A:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 08:01:27.771700 :94B2110B:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 08:01:27.772183 :94B2110C: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-05-09 08:01:27.772222 :94B21114: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-05-09 08:01:27.778581 :94B21120:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 08:24:22.500617 :94B4167E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 08:24:22.500 2025-05-09 08:24:22.500619 :94B4167F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12488 pso_num=28 pso_serial#=15 2025-05-09 08:24:22.500788 :94B41680:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12488 osp_idx=26 osp_ver=94874 osp_pg=0 (spawn #163749) 2025-05-09 08:24:22.500789 :94B41681:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 08:24:22.500790 :94B41682:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=150 time=4049839656 2025-05-09 08:24:22.500790 :94B41683:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 08:24:22.500791 :94B41684:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 08:24:22.500791 :94B41685:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 08:24:22.500791 :94B41686:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 08:24:22.500792 :94B41687:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 08:24:22.501717 :94B41688: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-05-09 08:24:22.501765 :94B4168E: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-05-09 08:24:22.616529 :94B416CC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-09 08:24:24.788634 :94B417C1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-09 08:24:24.786 2025-05-09 08:24:24.788636 :94B417C2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10664 pso_num=28 pso_serial#=16 2025-05-09 08:24:24.788768 :94B417C3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10664 osp_idx=26 osp_ver=94875 osp_pg=0 (spawn #163751) 2025-05-09 08:24:24.788769 :94B417C4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 08:24:24.788770 :94B417C5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=151 time=4049841937 2025-05-09 08:24:24.788770 :94B417C6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 08:24:24.788771 :94B417C7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 08:24:24.788771 :94B417C8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 08:24:24.788771 :94B417C9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 08:24:24.788772 :94B417CA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 08:24:24.789274 :94B417CB: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-05-09 08:24:24.789319 :94B417D1: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: 10664, M002)