Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250508082336\orcl_m002_12412_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:37514M/63366M, Ph+PgF:44924M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 12412, image: ORACLE.EXE (M002) *** 2025-05-08 08:23:36.996 *** SESSION ID:(193.46951) 2025-05-08 08:23:36.996 *** 2025-05-08 08:23:36.996 Process diagnostic dump for ORACLE.EXE (M002), OS id=12412, pid: 24, proc_ser: 63, sid: 193, sess_ser: 46951 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000084 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000110 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x471a, blocks=0x1 wait_id=1614 seq_num=1615 snap_id=1 wait times: snap=0.000124 sec, exc=0.000124 sec, total=0.000124 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000352 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x45a2, blocks=0x1 wait_id=1613 seq_num=1614 snap_id=1 wait times: snap=0.002942 sec, exc=0.002942 sec, total=0.002942 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000335 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x4762, blocks=0x1 wait_id=1612 seq_num=1613 snap_id=1 wait times: snap=0.006313 sec, exc=0.006313 sec, total=0.006313 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000186 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x480a, blocks=0x1 wait_id=1611 seq_num=1612 snap_id=1 wait times: snap=0.000081 sec, exc=0.000081 sec, total=0.000081 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000341 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x4702, blocks=0x1 wait_id=1610 seq_num=1611 snap_id=1 wait times: snap=0.003499 sec, exc=0.003499 sec, total=0.003499 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000350 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x46e2, blocks=0x1 wait_id=1609 seq_num=1610 snap_id=1 wait times: snap=0.005299 sec, exc=0.005299 sec, total=0.005299 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x46a2, blocks=0x1 wait_id=1608 seq_num=1609 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.000191 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x4692, blocks=0x1 wait_id=1607 seq_num=1608 snap_id=1 wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000336 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x4582, blocks=0x1 wait_id=1606 seq_num=1607 snap_id=1 wait times: snap=0.003012 sec, exc=0.003012 sec, total=0.003012 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000151 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x46ba, blocks=0x1 wait_id=1605 seq_num=1606 snap_id=1 wait times: snap=0.000079 sec, exc=0.000079 sec, total=0.000079 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000283 sec of elapsed time Sampled Session History of session 193 serial 46951 --------------------------------------------------- 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:23:36] waited for 'db file sequential read', seq_num: 1288 p1: 'file#'=0x2 p2: 'block#'=0x942 p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:23:35] idle wait at each sample [session created at: 08:23:36] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:23:36 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-05-08 08:23:37.012 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 12412, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-08 08:13:32.059250 :94424091:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:13:32.059293 :94424092:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:13:32.059547 :94424093:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:13:32.059571 :94424094:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:13:32.059859 :94424095:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:13:32.059872 :94424096:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:13:32.060083 :94424097:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:13:32.060096 :94424098:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:13:32.060308 :94424099:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:13:32.060322 :9442409A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:13:32.060529 :9442409B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:13:32.060542 :9442409C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:13:32.062869 :9442409D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:13:59.513654 :9442494C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:13:59.502 2025-05-08 08:13:59.513655 :9442494D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6844 pso_num=24 pso_serial#=51 2025-05-08 08:13:59.513788 :9442494E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6844 osp_idx=22 osp_ver=246890 osp_pg=0 (spawn #160340) 2025-05-08 08:13:59.513788 :9442494F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:13:59.513789 :94424950:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=12 time=3962816671 2025-05-08 08:13:59.513789 :94424951:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:13:59.513789 :94424952:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:13:59.513790 :94424953:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:13:59.513790 :94424954:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:13:59.513791 :94424955:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:13:59.514271 :94424956:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:14:29.616746 :944252FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:14:54.605123 :94425B46:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:14:54.594 2025-05-08 08:14:54.605125 :94425B47:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11868 pso_num=24 pso_serial#=52 2025-05-08 08:14:54.605265 :94425B48:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11868 osp_idx=22 osp_ver=246891 osp_pg=0 (spawn #160342) 2025-05-08 08:14:54.605266 :94425B49:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:14:54.605266 :94425B4A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=13 time=3962871765 2025-05-08 08:14:54.605267 :94425B4B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:14:54.605267 :94425B4C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:14:54.605267 :94425B4D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:14:54.605268 :94425B4E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:14:54.605268 :94425B4F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:14:54.605757 :94425B50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:14:59.603683 :94425D57:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-08 08:15:29.709551 :9442682E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:15:59.709088 :944271FD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:15:59.698 2025-05-08 08:15:59.709090 :944271FE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12952 pso_num=24 pso_serial#=53 2025-05-08 08:15:59.709225 :944271FF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12952 osp_idx=22 osp_ver=246892 osp_pg=0 (spawn #160344) 2025-05-08 08:15:59.709226 :94427200:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:15:59.709226 :94427201:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=14 time=3962936875 2025-05-08 08:15:59.709227 :94427202:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:15:59.709227 :94427203:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:15:59.709228 :94427204:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:15:59.709228 :94427205:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:15:59.709228 :94427206:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:15:59.709704 :94427207:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:16:29.781153 :94427BAB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:16:59.777310 :9442857A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:16:59.766 2025-05-08 08:16:59.777312 :9442857B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12112 pso_num=24 pso_serial#=54 2025-05-08 08:16:59.777452 :9442857C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12112 osp_idx=22 osp_ver=246893 osp_pg=0 (spawn #160346) 2025-05-08 08:16:59.777453 :9442857D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:16:59.777453 :9442857E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=15 time=3962996937 2025-05-08 08:16:59.777453 :9442857F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:16:59.777454 :94428580:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:16:59.777454 :94428581:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:16:59.777454 :94428582:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:16:59.777454 :94428583:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:16:59.777939 :94428584:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:17:29.848657 :94428F37:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:17:59.856552 :944298FA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:17:59.845 2025-05-08 08:17:59.856554 :944298FB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10660 pso_num=24 pso_serial#=55 2025-05-08 08:17:59.856689 :944298FC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10660 osp_idx=22 osp_ver=246894 osp_pg=0 (spawn #160349) 2025-05-08 08:17:59.856690 :944298FD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:17:59.856690 :944298FE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=16 time=3963057015 2025-05-08 08:17:59.856690 :944298FF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:17:59.856691 :94429900:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:17:59.856691 :94429901:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:17:59.856692 :94429902:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:17:59.856692 :94429903:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:17:59.857160 :94429904:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:18:29.943877 :9442A301:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:18:59.956707 :9442ACC3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:18:59.946 2025-05-08 08:18:59.956710 :9442ACC4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12236 pso_num=24 pso_serial#=56 2025-05-08 08:18:59.956844 :9442ACC5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12236 osp_idx=22 osp_ver=246895 osp_pg=0 (spawn #160351) 2025-05-08 08:18:59.956845 :9442ACC6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:18:59.956845 :9442ACC7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=17 time=3963117109 2025-05-08 08:18:59.956847 :9442ACC8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:18:59.956847 :9442ACC9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:18:59.956847 :9442ACCA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:18:59.956848 :9442ACCB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:18:59.956848 :9442ACCC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:18:59.957325 :9442ACCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:19:30.068275 :9442B667:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:20:00.064683 :9442C077:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:20:00.053 2025-05-08 08:20:00.064686 :9442C078:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11904 pso_num=24 pso_serial#=57 2025-05-08 08:20:00.064827 :9442C079:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11904 osp_idx=22 osp_ver=246896 osp_pg=0 (spawn #160353) 2025-05-08 08:20:00.064827 :9442C07A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:20:00.064828 :9442C07B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=18 time=3963177218 2025-05-08 08:20:00.064828 :9442C07C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:20:00.064829 :9442C07D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:20:00.064829 :9442C07E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:20:00.064829 :9442C07F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:20:00.064830 :9442C080:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:20:00.065317 :9442C081:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:20:30.154195 :9442CAA7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:21:00.167816 :9442D439:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:21:00.157 2025-05-08 08:21:00.167818 :9442D43A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12128 pso_num=24 pso_serial#=58 2025-05-08 08:21:00.168007 :9442D43B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12128 osp_idx=22 osp_ver=246897 osp_pg=0 (spawn #160355) 2025-05-08 08:21:00.168007 :9442D43C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:21:00.168008 :9442D43D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=19 time=3963237328 2025-05-08 08:21:00.168008 :9442D43E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:21:00.168009 :9442D43F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:21:00.168009 :9442D440:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:21:00.168009 :9442D441:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:21:00.168010 :9442D442:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:21:00.168505 :9442D443:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:21:30.237280 :9442DE1C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:22:00.245983 :9442E780:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:22:00.235 2025-05-08 08:22:00.245985 :9442E781:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3500 pso_num=24 pso_serial#=59 2025-05-08 08:22:00.246125 :9442E782:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3500 osp_idx=22 osp_ver=246898 osp_pg=0 (spawn #160357) 2025-05-08 08:22:00.246126 :9442E783:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:22:00.246126 :9442E784:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=20 time=3963297406 2025-05-08 08:22:00.246127 :9442E785:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:22:00.246127 :9442E786:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:22:00.246127 :9442E787:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:22:00.246128 :9442E788:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:22:00.246128 :9442E789:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:22:00.246614 :9442E78A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:22:30.319191 :9442F131:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:23:00.334264 :9442FB0F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:23:00.323 2025-05-08 08:23:00.334266 :9442FB10:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12968 pso_num=24 pso_serial#=60 2025-05-08 08:23:00.334403 :9442FB11:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12968 osp_idx=22 osp_ver=246899 osp_pg=0 (spawn #160359) 2025-05-08 08:23:00.334404 :9442FB12:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:23:00.334405 :9442FB13:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=21 time=3963357500 2025-05-08 08:23:00.334405 :9442FB14:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:23:00.334405 :9442FB15:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:23:00.334405 :9442FB16:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:23:00.334406 :9442FB17:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:23:00.334406 :9442FB18:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:23:00.334907 :9442FB19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:23:30.435155 :944304F6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:23:33.476522 :94433DDB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:23:33.471 2025-05-08 08:23:33.476524 :94433DDC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6832 pso_num=24 pso_serial#=61 2025-05-08 08:23:33.476649 :94433DDD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6832 osp_idx=22 osp_ver=246900 osp_pg=0 (spawn #160361) 2025-05-08 08:23:33.476649 :94433DDE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:23:33.476650 :94433DDF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=22 time=3963390640 2025-05-08 08:23:33.476650 :94433DE0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:23:33.476651 :94433DE1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:23:33.476651 :94433DE2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:23:33.476651 :94433DE3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:23:33.476652 :94433DE4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:23:33.477148 :94433DE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:23:33.477193 :94433DEB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-08 08:23:33.481280 :94433E0C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:23:33.481324 :94433E0D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:23:33.481564 :94433E0E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:23:33.481579 :94433E0F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:23:33.481787 :94433E10:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:23:33.481800 :94433E11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:23:33.482079 :94433E12:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:23:33.482091 :94433E13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:23:33.482298 :94433E14:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:23:33.482310 :94433E15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:23:33.482516 :94433E16:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-08 08:23:33.482528 :94433E17:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-08 08:23:33.484811 :94433E18:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:23:34.202054 :94433F54:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:23:34.200 2025-05-08 08:23:34.202055 :94433F55:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13300 pso_num=24 pso_serial#=62 2025-05-08 08:23:34.202218 :94433F56:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13300 osp_idx=22 osp_ver=246901 osp_pg=0 (spawn #160362) 2025-05-08 08:23:34.202219 :94433F57:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:23:34.202219 :94433F58:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=23 time=3963391359 2025-05-08 08:23:34.202220 :94433F59:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:23:34.202220 :94433F5A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:23:34.202220 :94433F5B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:23:34.202221 :94433F5C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:23:34.202221 :94433F5D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:23:34.203597 :94433F5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:23:34.203644 :94433F64:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-08 08:23:34.306456 :94433F96:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-08 08:23:36.370288 :94434069:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-08 08:23:36.356 2025-05-08 08:23:36.370290 :9443406A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12412 pso_num=24 pso_serial#=63 2025-05-08 08:23:36.370426 :9443406B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12412 osp_idx=22 osp_ver=246902 osp_pg=0 (spawn #160364) 2025-05-08 08:23:36.370427 :9443406C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:23:36.370427 :9443406D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=3963393531 2025-05-08 08:23:36.370428 :9443406E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:23:36.370428 :9443406F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:23:36.370428 :94434070:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:23:36.370429 :94434071:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:23:36.370429 :94434072:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:23:36.370938 :94434073:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 08:23:36.370981 :94434079:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 12412, M002)