Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250628080319\orcl_m002_18204_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:36368M/63366M, Ph+PgF:42475M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 18204, image: ORACLE.EXE (M002) *** 2025-06-28 08:03:19.797 *** SESSION ID:(209.7331) 2025-06-28 08:03:19.797 *** 2025-06-28 08:03:19.797 Process diagnostic dump for ORACLE.EXE (M002), OS id=18204, pid: 26, proc_ser: 68, sid: 209, sess_ser: 7331 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000023 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000055 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x3c62, blocks=0x1 wait_id=1503 seq_num=1504 snap_id=1 wait times: snap=0.005652 sec, exc=0.005652 sec, total=0.005652 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000378 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x3c1a, blocks=0x1 wait_id=1502 seq_num=1503 snap_id=1 wait times: snap=0.004976 sec, exc=0.004976 sec, total=0.004976 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000252 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x3c0a, blocks=0x1 wait_id=1501 seq_num=1502 snap_id=1 wait times: snap=0.000537 sec, exc=0.000537 sec, total=0.000537 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000342 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x3bb2, blocks=0x1 wait_id=1500 seq_num=1501 snap_id=1 wait times: snap=0.003735 sec, exc=0.003735 sec, total=0.003735 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000140 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x3b72, blocks=0x1 wait_id=1499 seq_num=1500 snap_id=1 wait times: snap=0.000203 sec, exc=0.000203 sec, total=0.000203 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000145 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x3b22, blocks=0x1 wait_id=1498 seq_num=1499 snap_id=1 wait times: snap=0.000424 sec, exc=0.000424 sec, total=0.000424 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000153 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x3af2, blocks=0x1 wait_id=1497 seq_num=1498 snap_id=1 wait times: snap=0.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000174 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x3aaa, blocks=0x1 wait_id=1496 seq_num=1497 snap_id=1 wait times: snap=0.000139 sec, exc=0.000139 sec, total=0.000139 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000385 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x3a8a, blocks=0x1 wait_id=1495 seq_num=1496 snap_id=1 wait times: snap=0.005450 sec, exc=0.005450 sec, total=0.005450 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000215 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x3a42, blocks=0x1 wait_id=1494 seq_num=1495 snap_id=1 wait times: snap=0.001187 sec, exc=0.001187 sec, total=0.001187 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000212 sec of elapsed time Sampled Session History of session 209 serial 7331 --------------------------------------------------- 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:03:19] waited for 'ADR block file read', seq_num: 79 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:03:18] idle wait at each sample [session created at: 08:03:19] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:03:19 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-28 08:03:19.797 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 18204, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-28 07:55:35.269446 :AAADFB97:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16660 osp_idx=24 osp_ver=526374 osp_pg=0 (spawn #330546) 2025-06-28 07:55:35.269446 :AAADFB98:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 07:55:35.269446 :AAADFB99:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=189 time=4073078454 2025-06-28 07:55:35.269447 :AAADFB9A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 07:55:35.269447 :AAADFB9B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 07:55:35.269448 :AAADFB9C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 07:55:35.269448 :AAADFB9D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 07:55:35.269448 :AAADFB9E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 07:55:35.269977 :AAADFB9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 07:56:05.361918 :AAAE0629:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 07:56:18.278661 :AAAE0AD2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 07:56:18.266 2025-06-28 07:56:18.278663 :AAAE0AD3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8140 pso_num=26 pso_serial#=55 2025-06-28 07:56:18.278793 :AAAE0AD4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8140 osp_idx=24 osp_ver=526375 osp_pg=0 (spawn #330548) 2025-06-28 07:56:18.278794 :AAAE0AD5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 07:56:18.278794 :AAAE0AD6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=190 time=4073121469 2025-06-28 07:56:18.278795 :AAAE0AD7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 07:56:18.278795 :AAAE0AD8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 07:56:18.278795 :AAAE0AD9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 07:56:18.278796 :AAAE0ADA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 07:56:18.278796 :AAAE0ADB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 07:56:18.279284 :AAAE0ADC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 07:56:18.279323 :AAAE0AE2:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-28 07:56:18.283425 :AAAE0AFB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-06-28 07:56:18.283468 :AAAE0AFC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-06-28 07:56:18.283715 :AAAE0AFD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-06-28 07:56:18.283730 :AAAE0AFE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-06-28 07:56:18.283941 :AAAE0AFF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-06-28 07:56:18.283955 :AAAE0B00:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-06-28 07:56:18.284157 :AAAE0B01:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-06-28 07:56:18.284170 :AAAE0B02:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-06-28 07:56:18.284375 :AAAE0B03:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-06-28 07:56:18.284388 :AAAE0B04:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-06-28 07:56:18.284587 :AAAE0B05:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-06-28 07:56:18.284600 :AAAE0B06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-06-28 07:56:18.286983 :AAAE0B07:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 07:56:35.365945 :AAAE106F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 07:56:35.354 2025-06-28 07:56:35.365948 :AAAE1070:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16164 pso_num=26 pso_serial#=56 2025-06-28 07:56:35.366061 :AAAE1071:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16164 osp_idx=24 osp_ver=526376 osp_pg=0 (spawn #330550) 2025-06-28 07:56:35.366062 :AAAE1072:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 07:56:35.366062 :AAAE1073:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=191 time=4073138547 2025-06-28 07:56:35.366063 :AAAE1074:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 07:56:35.366063 :AAAE1075:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 07:56:35.366063 :AAAE1076:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 07:56:35.366063 :AAAE1077:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 07:56:35.366064 :AAAE1078:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 07:56:35.366558 :AAAE1079:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 07:57:00.381604 :AAAE1914:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-28 07:57:30.470523 :AAAE22CC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 07:57:35.437771 :AAAE244C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 07:57:35.427 2025-06-28 07:57:35.437773 :AAAE244D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14108 pso_num=26 pso_serial#=57 2025-06-28 07:57:35.437911 :AAAE244E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14108 osp_idx=24 osp_ver=526377 osp_pg=0 (spawn #330552) 2025-06-28 07:57:35.437911 :AAAE244F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 07:57:35.437912 :AAAE2450:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=192 time=4073198625 2025-06-28 07:57:35.437912 :AAAE2451:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 07:57:35.437913 :AAAE2452:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 07:57:35.437913 :AAAE2453:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 07:57:35.437914 :AAAE2454:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 07:57:35.437914 :AAAE2455:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 07:57:35.438382 :AAAE2456:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 07:58:05.502309 :AAAE2E2D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 07:58:35.484237 :AAAE3830:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 07:58:35.473 2025-06-28 07:58:35.484239 :AAAE3831:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17560 pso_num=26 pso_serial#=58 2025-06-28 07:58:35.484378 :AAAE3832:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17560 osp_idx=24 osp_ver=526378 osp_pg=0 (spawn #330554) 2025-06-28 07:58:35.484378 :AAAE3833:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 07:58:35.484379 :AAAE3834:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=193 time=4073258672 2025-06-28 07:58:35.484379 :AAAE3835:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 07:58:35.484380 :AAAE3836:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 07:58:35.484380 :AAAE3837:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 07:58:35.484381 :AAAE3838:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 07:58:35.484381 :AAAE3839:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 07:58:35.484868 :AAAE383A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 07:59:05.560815 :AAAE4223:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 07:59:35.562315 :AAAE4BD5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 07:59:35.551 2025-06-28 07:59:35.562317 :AAAE4BD6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18232 pso_num=26 pso_serial#=59 2025-06-28 07:59:35.562431 :AAAE4BD7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18232 osp_idx=24 osp_ver=526379 osp_pg=0 (spawn #330556) 2025-06-28 07:59:35.562432 :AAAE4BD8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 07:59:35.562432 :AAAE4BD9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=194 time=4073318750 2025-06-28 07:59:35.562433 :AAAE4BDA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 07:59:35.562433 :AAAE4BDB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 07:59:35.562434 :AAAE4BDC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 07:59:35.562434 :AAAE4BDD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 07:59:35.562434 :AAAE4BDE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 07:59:35.562971 :AAAE4BDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:00:05.649405 :AAAE55BE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:00:20.202023 :AAAE5B6B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:00:20.200 2025-06-28 08:00:20.202025 :AAAE5B6C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17016 pso_num=26 pso_serial#=60 2025-06-28 08:00:20.202160 :AAAE5B6D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17016 osp_idx=24 osp_ver=526380 osp_pg=0 (spawn #330558) 2025-06-28 08:00:20.202161 :AAAE5B6E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 08:00:20.202161 :AAAE5B6F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=195 time=4073363375 2025-06-28 08:00:20.202161 :AAAE5B70:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 08:00:20.202162 :AAAE5B71:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 08:00:20.202162 :AAAE5B72:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 08:00:20.202162 :AAAE5B73:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 08:00:20.202163 :AAAE5B74:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 08:00:20.202675 :AAAE5B75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.202724 :AAAE5B7B:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-28 08:00:20.210040 :AAAE5B89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.211114 :AAAE5B91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.211661 :AAAE5B99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.212106 :AAAE5BA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.212543 :AAAE5BA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.214995 :AAAE5BB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.216115 :AAAE5BB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.266875 :AAAE5BC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.267853 :AAAE5BC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.268112 :AAAE5BD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.268330 :AAAE5BD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:00:20.270054 :AAAE5BE1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:00:35.647775 :AAAE6116:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:00:35.636 2025-06-28 08:00:35.647777 :AAAE6117:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10372 pso_num=26 pso_serial#=61 2025-06-28 08:00:35.647910 :AAAE6118:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10372 osp_idx=24 osp_ver=526381 osp_pg=0 (spawn #330560) 2025-06-28 08:00:35.647911 :AAAE6119:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 08:00:35.647911 :AAAE611A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=196 time=4073378829 2025-06-28 08:00:35.647911 :AAAE611B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 08:00:35.647911 :AAAE611C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 08:00:35.647912 :AAAE611D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 08:00:35.647912 :AAAE611E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 08:00:35.647912 :AAAE611F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 08:00:35.648442 :AAAE6120:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:01:05.721177 :AAAE6CC9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:01:35.767601 :AAAE770A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:01:35.764 2025-06-28 08:01:35.767603 :AAAE770B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14700 pso_num=26 pso_serial#=62 2025-06-28 08:01:35.767717 :AAAE770C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14700 osp_idx=24 osp_ver=526382 osp_pg=0 (spawn #330563) 2025-06-28 08:01:35.767718 :AAAE770D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 08:01:35.767718 :AAAE770E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=197 time=4073438938 2025-06-28 08:01:35.767719 :AAAE770F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 08:01:35.767719 :AAAE7710:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 08:01:35.767720 :AAAE7711:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 08:01:35.767720 :AAAE7712:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 08:01:35.767720 :AAAE7713:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 08:01:35.768203 :AAAE7714:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:02:05.870209 :AAAE80C9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:02:35.873127 :AAAE8A8D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:02:35.862 2025-06-28 08:02:35.873130 :AAAE8A8E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16476 pso_num=26 pso_serial#=63 2025-06-28 08:02:35.873271 :AAAE8A8F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16476 osp_idx=24 osp_ver=526383 osp_pg=0 (spawn #330565) 2025-06-28 08:02:35.873271 :AAAE8A90:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 08:02:35.873272 :AAAE8A91:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=198 time=4073499063 2025-06-28 08:02:35.873272 :AAAE8A92:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 08:02:35.873272 :AAAE8A93:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 08:02:35.873273 :AAAE8A94:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 08:02:35.873274 :AAAE8A95:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 08:02:35.873274 :AAAE8A96:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 08:02:35.873760 :AAAE8A97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:03:05.945766 :AAAE9470:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:03:15.554783 :AAAED04B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:03:15.541 2025-06-28 08:03:15.554785 :AAAED04C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=2964 pso_num=26 pso_serial#=64 2025-06-28 08:03:15.554923 :AAAED04D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=2964 (legacy spawn) 2025-06-28 08:03:15.561467 :AAAED05F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:03:15.561660 :AAAED06A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-28 08:03:15.562473 :AAAED06B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:03:17.051404 :AAAED3BD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:03:17.041 2025-06-28 08:03:17.051405 :AAAED3BE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12684 pso_num=26 pso_serial#=65 2025-06-28 08:03:17.051560 :AAAED3C2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12684 osp_idx=24 osp_ver=526385 osp_pg=0 (spawn #330567) 2025-06-28 08:03:17.051560 :AAAED3C3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 08:03:17.051561 :AAAED3C4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=199 time=4073540235 2025-06-28 08:03:17.051561 :AAAED3C5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 08:03:17.051562 :AAAED3C6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 08:03:17.051562 :AAAED3C7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 08:03:17.051562 :AAAED3C8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 08:03:17.051563 :AAAED3C9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 08:03:17.052423 :AAAED3EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:03:17.052464 :AAAED3F5:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-28 08:03:17.164021 :AAAED699:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:03:17.802104 :AAAED6D9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:03:17.792 2025-06-28 08:03:17.802106 :AAAED6DA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14372 pso_num=26 pso_serial#=66 2025-06-28 08:03:17.802235 :AAAED6DB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14372 (legacy spawn) 2025-06-28 08:03:17.808664 :AAAED6DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:03:17.808821 :AAAED6E5:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-28 08:03:17.809597 :AAAED6E6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:03:17.818290 :AAAED6E9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:03:17.807 2025-06-28 08:03:17.818292 :AAAED6EA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=732 pso_num=26 pso_serial#=67 2025-06-28 08:03:17.818429 :AAAED6EB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=732 (legacy spawn) 2025-06-28 08:03:17.824940 :AAAED6EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 08:03:17.825096 :AAAED6F5:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-28 08:03:17.825929 :AAAED6F6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-28 08:03:19.265281 :AAAED7DC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 08:03:19.260 2025-06-28 08:03:19.265282 :AAAED7DD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18204 pso_num=26 pso_serial#=68 2025-06-28 08:03:19.265410 :AAAED7DE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18204 osp_idx=24 osp_ver=526388 osp_pg=0 (spawn #330569) 2025-06-28 08:03:19.265411 :AAAED7DF:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 08:03:19.265411 :AAAED7E0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=200 time=4073542438 2025-06-28 08:03:19.265411 :AAAED7E1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 08:03:19.265412 :AAAED7E2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 08:03:19.265412 :AAAED7E3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 08:03:19.265412 :AAAED7E4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 08:03:19.265413 :AAAED7E5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 08:03:19.265901 :AAAED7E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 08:03:19.265941 :AAAED7EC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 18204, M002)