Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915082514\orcl_m002_23316_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:34735M/63366M, Ph+PgF:39315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 23316, image: ORACLE.EXE (M002) *** 2025-09-15 08:25:14.259 *** SESSION ID:(225.439) 2025-09-15 08:25:14.259 *** 2025-09-15 08:25:14.259 Process diagnostic dump for ORACLE.EXE (M002), OS id=23316, pid: 28, proc_ser: 197, sid: 225, sess_ser: 439 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x4a4a, blocks=0x1 wait_id=1630 seq_num=1631 snap_id=1 wait times: snap=0.000904 sec, exc=0.000904 sec, total=0.000904 sec wait times: max=infinite, heur=0.000904 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.000153 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x7422, blocks=0x1 wait_id=1629 seq_num=1630 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000188 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x7412, blocks=0x1 wait_id=1628 seq_num=1629 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x741a, blocks=0x1 wait_id=1627 seq_num=1628 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x6de2, blocks=0x1 wait_id=1626 seq_num=1627 snap_id=1 wait times: snap=0.000410 sec, exc=0.000410 sec, total=0.000410 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000154 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x6db2, blocks=0x1 wait_id=1625 seq_num=1626 snap_id=1 wait times: snap=0.000117 sec, exc=0.000117 sec, total=0.000117 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x6da2, blocks=0x1 wait_id=1624 seq_num=1625 snap_id=1 wait times: snap=0.000407 sec, exc=0.000407 sec, total=0.000407 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000198 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x6cda, blocks=0x1 wait_id=1623 seq_num=1624 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.000211 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x6caa, blocks=0x1 wait_id=1622 seq_num=1623 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x6cca, blocks=0x1 wait_id=1621 seq_num=1622 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x6c9a, blocks=0x1 wait_id=1620 seq_num=1621 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000134 sec of elapsed time Sampled Session History of session 225 serial 439 --------------------------------------------------- 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:25:14] waited for 'db file sequential read', seq_num: 1604 p1: 'file#'=0x2 p2: 'block#'=0x6c4a p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:25:13] idle wait at each sample [session created at: 08:25:13] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:25:14 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-15 08:25:14.259 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 23316, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-15 06:17:56.643583 :CDA06786:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 06:29:01.355975 :CDA1455A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 06:29:01.343 2025-09-15 06:29:01.355977 :CDA1455B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24308 pso_num=28 pso_serial#=190 2025-09-15 06:29:01.356131 :CDA1455C:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24308 osp_idx=26 osp_ver=327658 osp_pg=0 (spawn #593691) 2025-09-15 06:29:01.356132 :CDA1455D:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:29:01.356133 :CDA1455E:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=0 time=2303392658 2025-09-15 06:29:01.356134 :CDA1455F:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:29:01.356134 :CDA14560:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:29:01.356134 :CDA14561:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:29:01.356135 :CDA14562:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:29:01.356136 :CDA14563:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:29:01.356711 :CDA14564: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-09-15 06:29:01.356754 :CDA1456A: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-09-15 06:29:01.360834 :CDA14583:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:29:01.360878 :CDA14584:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:29:01.361146 :CDA14585:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:29:01.361163 :CDA14586:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:29:01.361388 :CDA14587:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:29:01.361401 :CDA14588:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:29:01.361617 :CDA14589:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:29:01.361630 :CDA1458A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:29:01.361839 :CDA1458B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:29:01.361851 :CDA1458C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:29:01.362064 :CDA1458D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:29:01.362076 :CDA1458E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:29:01.364397 :CDA1458F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 06:59:05.106040 :CDA39E2C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 06:59:05.093 2025-09-15 06:59:05.106043 :CDA39E2D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14024 pso_num=28 pso_serial#=191 2025-09-15 06:59:05.106185 :CDA39E2E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14024 osp_idx=26 osp_ver=327659 osp_pg=0 (spawn #593759) 2025-09-15 06:59:05.106186 :CDA39E2F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:59:05.106186 :CDA39E30:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=1 time=2305196408 2025-09-15 06:59:05.106187 :CDA39E31:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:59:05.106187 :CDA39E32:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:59:05.106188 :CDA39E33:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:59:05.106188 :CDA39E34:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:59:05.106188 :CDA39E35:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:59:05.106717 :CDA39E36: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-09-15 06:59:05.106756 :CDA39E3C: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-09-15 06:59:05.110913 :CDA39E55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:59:05.110961 :CDA39E56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:59:05.111223 :CDA39E57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:59:05.111239 :CDA39E58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:59:05.111452 :CDA39E59:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:59:05.111465 :CDA39E5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:59:05.111674 :CDA39E5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:59:05.111687 :CDA39E5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:59:05.111892 :CDA39E5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:59:05.111905 :CDA39E5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:59:05.112110 :CDA39E5F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 06:59:05.112123 :CDA39E60:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 06:59:05.114659 :CDA39E61:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 07:00:06.599112 :CDA3B30A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 07:00:06.592 2025-09-15 07:00:06.599114 :CDA3B30B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25176 pso_num=28 pso_serial#=192 2025-09-15 07:00:06.599258 :CDA3B30C:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=25176 osp_idx=26 osp_ver=327660 osp_pg=0 (spawn #593763) 2025-09-15 07:00:06.599259 :CDA3B30D:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:00:06.599259 :CDA3B30E:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=2 time=2305257893 2025-09-15 07:00:06.599260 :CDA3B30F:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:00:06.599260 :CDA3B310:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:00:06.599261 :CDA3B311:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:00:06.599261 :CDA3B312:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:00:06.599261 :CDA3B313:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:00:06.599805 :CDA3B314: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-09-15 07:00:06.599852 :CDA3B31A: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-09-15 07:00:06.607769 :CDA3B328: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-09-15 07:00:06.608957 :CDA3B330: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-09-15 07:00:06.609517 :CDA3B338: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-09-15 07:00:06.610022 :CDA3B340: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-09-15 07:00:06.610528 :CDA3B348: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-09-15 07:00:06.613173 :CDA3B350: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-09-15 07:00:06.614438 :CDA3B358: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-09-15 07:00:06.667128 :CDA3B360: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-09-15 07:00:06.668000 :CDA3B368: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-09-15 07:00:06.668232 :CDA3B370: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-09-15 07:00:06.668533 :CDA3B378: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-09-15 07:00:06.670597 :CDA3B380:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 07:29:08.937053 :CDA60102:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 07:29:08.924 2025-09-15 07:29:08.937055 :CDA60103:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23820 pso_num=28 pso_serial#=193 2025-09-15 07:29:08.937207 :CDA60104:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23820 osp_idx=26 osp_ver=327661 osp_pg=0 (spawn #593830) 2025-09-15 07:29:08.937207 :CDA60105:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:29:08.937208 :CDA60106:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=3 time=2307000237 2025-09-15 07:29:08.937208 :CDA60107:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:29:08.937209 :CDA60108:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:29:08.937209 :CDA60109:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:29:08.937210 :CDA6010A:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:29:08.937210 :CDA6010B:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:29:08.937716 :CDA6010C: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-09-15 07:29:08.937755 :CDA60112: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-09-15 07:29:08.941654 :CDA6012B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:29:08.941712 :CDA6012C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:29:08.941991 :CDA6012D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:29:08.942009 :CDA6012E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:29:08.942226 :CDA6012F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:29:08.942239 :CDA60130:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:29:08.942451 :CDA60131:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:29:08.942464 :CDA60132:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:29:08.942671 :CDA60133:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:29:08.942683 :CDA60134:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:29:08.942887 :CDA60135:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:29:08.942900 :CDA60136:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:29:08.945180 :CDA60137:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 07:59:12.804784 :CDA859E7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 07:59:12.792 2025-09-15 07:59:12.804786 :CDA859E8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24416 pso_num=28 pso_serial#=194 2025-09-15 07:59:12.804935 :CDA859E9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24416 osp_idx=26 osp_ver=327662 osp_pg=0 (spawn #593898) 2025-09-15 07:59:12.804935 :CDA859EA:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:59:12.804936 :CDA859EB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=4 time=2308804096 2025-09-15 07:59:12.804937 :CDA859EC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:59:12.804937 :CDA859ED:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:59:12.804938 :CDA859EE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:59:12.804938 :CDA859EF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:59:12.804939 :CDA859F0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:59:12.805451 :CDA859F1: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-09-15 07:59:12.805490 :CDA859F7: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-09-15 07:59:12.809526 :CDA85A10:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:59:12.809574 :CDA85A11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:59:12.809837 :CDA85A12:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:59:12.809853 :CDA85A13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:59:12.810067 :CDA85A14:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:59:12.810080 :CDA85A15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:59:12.810284 :CDA85A16:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:59:12.810296 :CDA85A17:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:59:12.810500 :CDA85A18:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:59:12.810513 :CDA85A19:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:59:12.810714 :CDA85A1A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-15 07:59:12.810727 :CDA85A1B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-15 07:59:12.813125 :CDA85A1C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 08:00:14.307632 :CDA86EBD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 08:00:14.302 2025-09-15 08:00:14.307634 :CDA86EBE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22444 pso_num=28 pso_serial#=195 2025-09-15 08:00:14.307761 :CDA86EBF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22444 osp_idx=26 osp_ver=327663 osp_pg=0 (spawn #593902) 2025-09-15 08:00:14.307761 :CDA86EC0:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:00:14.307762 :CDA86EC1:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=5 time=2308865596 2025-09-15 08:00:14.307762 :CDA86EC2:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:00:14.307762 :CDA86EC3:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:00:14.307763 :CDA86EC4:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:00:14.307763 :CDA86EC5:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:00:14.307764 :CDA86EC6:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:00:14.308239 :CDA86EC7: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-09-15 08:00:14.308287 :CDA86ECD: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-09-15 08:00:14.315573 :CDA86EDB: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-09-15 08:00:14.316717 :CDA86EE3: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-09-15 08:00:14.317305 :CDA86EEB: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-09-15 08:00:14.317776 :CDA86EF3: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-09-15 08:00:14.318212 :CDA86EFB: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-09-15 08:00:14.320747 :CDA86F03: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-09-15 08:00:14.321847 :CDA86F0B: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-09-15 08:00:14.382930 :CDA86F13: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-09-15 08:00:14.383799 :CDA86F1B: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-09-15 08:00:14.384115 :CDA86F23: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-09-15 08:00:14.384341 :CDA86F2B: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-09-15 08:00:14.386112 :CDA86F33:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 08:25:11.325593 :CDAAA243:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 08:25:11.312 2025-09-15 08:25:11.325595 :CDAAA244:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25124 pso_num=28 pso_serial#=196 2025-09-15 08:25:11.325801 :CDAAA245:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=25124 osp_idx=26 osp_ver=327664 osp_pg=0 (spawn #593960) 2025-09-15 08:25:11.325802 :CDAAA246:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:25:11.325803 :CDAAA247:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=6 time=2310362627 2025-09-15 08:25:11.325803 :CDAAA248:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:25:11.325804 :CDAAA249:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:25:11.325804 :CDAAA24A:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:25:11.325805 :CDAAA24B:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:25:11.325805 :CDAAA24C:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:25:11.327077 :CDAAA24D: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-09-15 08:25:11.327119 :CDAAA253: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-09-15 08:25:11.449901 :CDAAA285:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-15 08:25:13.557471 :CDAAA37C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-15 08:25:13.556 2025-09-15 08:25:13.557473 :CDAAA37D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23316 pso_num=28 pso_serial#=197 2025-09-15 08:25:13.557609 :CDAAA37E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23316 osp_idx=26 osp_ver=327665 osp_pg=0 (spawn #593962) 2025-09-15 08:25:13.557610 :CDAAA37F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:25:13.557611 :CDAAA380:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=7 time=2310364846 2025-09-15 08:25:13.557611 :CDAAA381:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:25:13.557611 :CDAAA382:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:25:13.557612 :CDAAA383:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:25:13.557612 :CDAAA384:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:25:13.557613 :CDAAA385:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:25:13.558166 :CDAAA386: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-09-15 08:25:13.558211 :CDAAA38C: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: 23316, M002)