Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614222534\orcl_m002_16416_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:36682M/63366M, Ph+PgF:43137M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 16416, image: ORACLE.EXE (M002) *** 2025-06-14 22:25:34.554 *** SESSION ID:(233.25545) 2025-06-14 22:25:34.554 *** 2025-06-14 22:25:34.554 Process diagnostic dump for ORACLE.EXE (M002), OS id=16416, pid: 29, proc_ser: 241, sid: 233, sess_ser: 25545 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x52da, blocks=0x1 wait_id=1643 seq_num=1644 snap_id=1 wait times: snap=0.002443 sec, exc=0.002443 sec, total=0.002443 sec wait times: max=infinite, heur=0.002443 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.000218 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x52ba, blocks=0x1 wait_id=1642 seq_num=1643 snap_id=1 wait times: snap=0.001298 sec, exc=0.001298 sec, total=0.001298 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000160 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x4a82, blocks=0x1 wait_id=1641 seq_num=1642 snap_id=1 wait times: snap=0.000123 sec, exc=0.000123 sec, total=0.000123 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000163 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x4d52, blocks=0x1 wait_id=1640 seq_num=1641 snap_id=1 wait times: snap=0.000846 sec, exc=0.000846 sec, total=0.000846 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000145 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x4d42, blocks=0x1 wait_id=1639 seq_num=1640 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.000167 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x4d3a, blocks=0x1 wait_id=1638 seq_num=1639 snap_id=1 wait times: snap=0.000087 sec, exc=0.000087 sec, total=0.000087 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000381 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x4c52, blocks=0x1 wait_id=1637 seq_num=1638 snap_id=1 wait times: snap=0.005740 sec, exc=0.005740 sec, total=0.005740 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000164 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x4c42, blocks=0x1 wait_id=1636 seq_num=1637 snap_id=1 wait times: snap=0.000904 sec, exc=0.000904 sec, total=0.000904 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000161 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x4a52, blocks=0x1 wait_id=1635 seq_num=1636 snap_id=1 wait times: snap=0.000108 sec, exc=0.000108 sec, total=0.000108 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000231 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x4b92, blocks=0x1 wait_id=1634 seq_num=1635 snap_id=1 wait times: snap=0.000154 sec, exc=0.000154 sec, total=0.000154 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000368 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x52da, blocks=0x1 wait_id=1643 seq_num=1644 snap_id=1 wait times: snap=0.002870 sec, exc=0.002870 sec, total=0.002870 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000218 sec of elapsed time Sampled Session History of session 233 serial 25545 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [1 sample, 22:25:34] waited for 'db file sequential read', seq_num: 1458 p1: 'file#'=0x2 p2: 'block#'=0x22ea p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 22:25:33] idle wait at each sample [session created at: 22:25:33] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 22:25:34 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 22:25:34.554 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 16416, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 21:16:17.948165 :A4BA8EA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 21:16:17.948207 :A4BA8EAD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 21:16:17.952307 :A4BA8ED6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:16:17.952356 :A4BA8ED7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:16:17.952605 :A4BA8ED8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:16:17.952620 :A4BA8ED9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:16:17.952831 :A4BA8EDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:16:17.952845 :A4BA8EDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:16:17.953052 :A4BA8EDC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:16:17.953065 :A4BA8EDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:16:17.953271 :A4BA8EDE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:16:17.953283 :A4BA8EDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:16:17.953538 :A4BA8EE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:16:17.953558 :A4BA8EE1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:16:17.955868 :A4BA8EE2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 21:46:21.518526 :A4BCEC52:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 21:46:21.506 2025-06-14 21:46:21.518529 :A4BCEC53:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12732 pso_num=29 pso_serial#=234 2025-06-14 21:46:21.518666 :A4BCEC54:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12732 osp_idx=27 osp_ver=12728 osp_pg=0 (spawn #285789) 2025-06-14 21:46:21.518669 :A4BCEC55:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 21:46:21.518669 :A4BCEC56:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=216 time=2913324704 2025-06-14 21:46:21.518669 :A4BCEC57:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 21:46:21.518670 :A4BCEC58:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 21:46:21.518670 :A4BCEC59:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 21:46:21.518670 :A4BCEC5A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 21:46:21.518671 :A4BCEC5B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 21:46:21.519181 :A4BCEC5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 21:46:21.519222 :A4BCEC62:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 21:46:21.523423 :A4BCEC7B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:46:21.523487 :A4BCEC7C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:46:21.523749 :A4BCEC7D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:46:21.523764 :A4BCEC7E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:46:21.523975 :A4BCEC7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:46:21.523989 :A4BCEC80:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:46:21.524202 :A4BCEC81:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:46:21.524214 :A4BCEC82:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:46:21.524503 :A4BCEC83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:46:21.524516 :A4BCEC84:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:46:21.524724 :A4BCEC85:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 21:46:21.524737 :A4BCEC86:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 21:46:21.526971 :A4BCEC87:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:00:24.591602 :A4BE07F2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:00:24.580 2025-06-14 22:00:24.591604 :A4BE07F3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14508 pso_num=29 pso_serial#=235 2025-06-14 22:00:24.591736 :A4BE07F4:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14508 osp_idx=27 osp_ver=12729 osp_pg=0 (spawn #285821) 2025-06-14 22:00:24.591737 :A4BE07F5:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:00:24.591738 :A4BE07F6:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=217 time=2914167782 2025-06-14 22:00:24.591738 :A4BE07F7:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:00:24.591739 :A4BE07F8:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:00:24.591740 :A4BE07F9:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:00:24.591740 :A4BE07FA:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:00:24.591740 :A4BE07FB:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:00:24.592295 :A4BE07FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.592345 :A4BE0802:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:00:24.599785 :A4BE0810:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.600935 :A4BE0818:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.601472 :A4BE0820:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.601919 :A4BE0828:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.602351 :A4BE0830:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.604871 :A4BE0838:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.605953 :A4BE0840:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.655766 :A4BE0848:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.656610 :A4BE0850:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.656842 :A4BE0858:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.657053 :A4BE0860:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:00:24.658978 :A4BE0868:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:01:23.337004 :A4BE1FA8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:01:23.324 2025-06-14 22:01:23.337006 :A4BE1FA9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16648 pso_num=29 pso_serial#=236 2025-06-14 22:01:23.337146 :A4BE1FAA:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16648 osp_idx=27 osp_ver=12730 osp_pg=0 (spawn #285825) 2025-06-14 22:01:23.337146 :A4BE1FAB:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:01:23.337147 :A4BE1FAC:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=218 time=2914226516 2025-06-14 22:01:23.337147 :A4BE1FAD:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:01:23.337148 :A4BE1FAE:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:01:23.337148 :A4BE1FAF:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:01:23.337148 :A4BE1FB0:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:01:23.337149 :A4BE1FB1:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:01:23.337658 :A4BE1FB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:01:23.337697 :A4BE1FB8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:01:23.345425 :A4BE1FCA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:06:23.927243 :A4BE8AD9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:06:23.915 2025-06-14 22:06:23.927244 :A4BE8ADA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16000 pso_num=29 pso_serial#=237 2025-06-14 22:06:23.927380 :A4BE8ADB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16000 osp_idx=27 osp_ver=12731 osp_pg=0 (spawn #285838) 2025-06-14 22:06:23.927380 :A4BE8ADC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:06:23.927381 :A4BE8ADD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=219 time=2914527110 2025-06-14 22:06:23.927381 :A4BE8ADE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:06:23.927382 :A4BE8ADF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:06:23.927382 :A4BE8AE0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:06:23.927382 :A4BE8AE1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:06:23.927383 :A4BE8AE2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:06:23.927899 :A4BE8AE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.927944 :A4BE8AE9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:06:23.928012 :A4BE8AF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.932592 :A4BE8B0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.985111 :A4BE8B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.986434 :A4BE8B1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.987341 :A4BE8B25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.987364 :A4BE8B28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.987409 :A4BE8B33:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:06:23.988378 :A4BE8B3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.988397 :A4BE8B41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.988424 :A4BE8B4C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:06:23.988865 :A4BE8B57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.991364 :A4BE8B5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.996445 :A4BE8B67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.001797 :A4BE8B6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.003118 :A4BE8B77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.006051 :A4BE8B7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.007250 :A4BE8B87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.007260 :A4BE8B88:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.01b.0016e0b6 2025-06-14 22:06:24.010600 :A4BE8B90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.010758 :A4BE8B9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.011031 :A4BE8BAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.011079 :A4BE8BAD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-06-14 22:06:24.011092 :A4BE8BAE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-06-14 22:06:24.013911 :A4BE8BCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.014014 :A4BE8BDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.014210 :A4BE8BE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.014253 :A4BE8BE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:ktcc.c:879 2025-06-14 22:06:24.014267 :A4BE8BE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:ktcc.c:879 2025-06-14 22:06:24.014451 :A4BE8BED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.014464 :A4BE8BF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.014488 :A4BE8BFC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:06:24.016365 :A4BE8C06:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:06:30.641553 :A4BE9155:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:06:30.640 2025-06-14 22:06:30.641556 :A4BE9156:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14240 pso_num=29 pso_serial#=238 2025-06-14 22:06:30.641698 :A4BE9157:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14240 (legacy spawn) 2025-06-14 22:06:30.648238 :A4BE9158:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:30.648409 :A4BE9160:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 22:06:30.649230 :A4BE9162:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:16:25.131588 :A4BF5A9B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:16:25.119 2025-06-14 22:16:25.131590 :A4BF5A9C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15220 pso_num=29 pso_serial#=239 2025-06-14 22:16:25.131733 :A4BF5A9D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15220 osp_idx=27 osp_ver=12733 osp_pg=0 (spawn #285862) 2025-06-14 22:16:25.131734 :A4BF5A9E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:16:25.131734 :A4BF5A9F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=220 time=2915128313 2025-06-14 22:16:25.131735 :A4BF5AA0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:16:25.131735 :A4BF5AA1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:16:25.131736 :A4BF5AA2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:16:25.131736 :A4BF5AA3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:16:25.131736 :A4BF5AA4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:16:25.132244 :A4BF5AA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:16:25.132285 :A4BF5AAB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:16:25.136451 :A4BF5ACB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 22:16:25.136494 :A4BF5ACC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 22:16:25.136743 :A4BF5ACD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 22:16:25.136757 :A4BF5ACE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 22:16:25.136963 :A4BF5ACF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 22:16:25.136976 :A4BF5AD0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 22:16:25.137177 :A4BF5AD1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 22:16:25.137190 :A4BF5AD2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 22:16:25.137477 :A4BF5AD3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 22:16:25.137490 :A4BF5AD4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 22:16:25.137705 :A4BF5AD5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-14 22:16:25.137717 :A4BF5AD6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-14 22:16:25.139986 :A4BF5AD7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:25:32.631457 :A4C016AE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:25:32.616 2025-06-14 22:25:32.631459 :A4C016AF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12612 pso_num=29 pso_serial#=240 2025-06-14 22:25:32.631609 :A4C016B0:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12612 osp_idx=27 osp_ver=12734 osp_pg=0 (spawn #285883) 2025-06-14 22:25:32.631609 :A4C016B1:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:25:32.631610 :A4C016B2:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=221 time=2915675813 2025-06-14 22:25:32.631610 :A4C016B3:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:25:32.631611 :A4C016B4:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:25:32.631611 :A4C016B5:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:25:32.631612 :A4C016B6:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:25:32.631613 :A4C016B7:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:25:32.632904 :A4C016B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:25:32.632949 :A4C016BE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 22:25:32.735423 :A4C016F0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-14 22:25:33.890165 :A4C0171F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-14 22:25:33.882 2025-06-14 22:25:33.890167 :A4C01720:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16416 pso_num=29 pso_serial#=241 2025-06-14 22:25:33.890303 :A4C01721:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16416 osp_idx=27 osp_ver=12735 osp_pg=0 (spawn #285885) 2025-06-14 22:25:33.890304 :A4C01722:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:25:33.890304 :A4C01723:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=222 time=2915677079 2025-06-14 22:25:33.890304 :A4C01724:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:25:33.890304 :A4C01725:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:25:33.890304 :A4C01726:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:25:33.890305 :A4C01727:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:25:33.890305 :A4C01728:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:25:33.890807 :A4C01729:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:25:33.890851 :A4C0172F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 16416, M002)