Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250624080101\orcl_m002_18080_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:36320M/63366M, Ph+PgF:42511M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 18080, image: ORACLE.EXE (M002) *** 2025-06-24 08:01:01.177 *** SESSION ID:(201.43221) 2025-06-24 08:01:01.177 *** 2025-06-24 08:01:01.177 Process diagnostic dump for ORACLE.EXE (M002), OS id=18080, pid: 25, proc_ser: 152, sid: 201, sess_ser: 43221 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x52da, blocks=0x1 wait_id=1630 seq_num=1631 snap_id=1 wait times: snap=0.004994 sec, exc=0.004994 sec, total=0.004994 sec wait times: max=infinite, heur=0.004994 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.000232 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x52ba, blocks=0x1 wait_id=1629 seq_num=1630 snap_id=1 wait times: snap=0.001154 sec, exc=0.001154 sec, total=0.001154 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000150 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x4a82, blocks=0x1 wait_id=1628 seq_num=1629 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.000139 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x4d52, blocks=0x1 wait_id=1627 seq_num=1628 snap_id=1 wait times: snap=0.000473 sec, exc=0.000473 sec, total=0.000473 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=1626 seq_num=1627 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.000155 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x4d3a, blocks=0x1 wait_id=1625 seq_num=1626 snap_id=1 wait times: snap=0.000085 sec, exc=0.000085 sec, total=0.000085 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000345 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x4c52, blocks=0x1 wait_id=1624 seq_num=1625 snap_id=1 wait times: snap=0.006199 sec, exc=0.006199 sec, total=0.006199 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000311 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x4c42, blocks=0x1 wait_id=1623 seq_num=1624 snap_id=1 wait times: snap=0.002686 sec, exc=0.002686 sec, total=0.002686 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000149 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x4a52, blocks=0x1 wait_id=1622 seq_num=1623 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.000143 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x4b92, blocks=0x1 wait_id=1621 seq_num=1622 snap_id=1 wait times: snap=0.000125 sec, exc=0.000125 sec, total=0.000125 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000189 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x4b7a, blocks=0x1 wait_id=1620 seq_num=1621 snap_id=1 wait times: snap=0.001462 sec, exc=0.001462 sec, total=0.001462 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time Sampled Session History of session 201 serial 43221 --------------------------------------------------- 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:01:00] waited for 'ADR block file read', seq_num: 696 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:00:59] idle wait at each sample [session created at: 08:01:00] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:01:00 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-24 08:01:01.177 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 18080, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-24 07:48:07.260857 :A8E74496:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12500 osp_idx=23 osp_ver=167020 osp_pg=0 (spawn #317206) 2025-06-24 07:48:07.260858 :A8E74497:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:48:07.260858 :A8E74498:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=143 time=3727030438 2025-06-24 07:48:07.260859 :A8E74499:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:48:07.260859 :A8E7449A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:48:07.260859 :A8E7449B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:48:07.260860 :A8E7449C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:48:07.260860 :A8E7449D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:48:07.261338 :A8E7449E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:48:32.299418 :A8E74CE0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-24 07:49:02.374262 :A8E756B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:49:07.349306 :A8E7586B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:49:07.338 2025-06-24 07:49:07.349308 :A8E7586C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17520 pso_num=25 pso_serial#=139 2025-06-24 07:49:07.349430 :A8E7586D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17520 osp_idx=23 osp_ver=167021 osp_pg=0 (spawn #317208) 2025-06-24 07:49:07.349431 :A8E7586E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:49:07.349431 :A8E7586F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=144 time=3727090532 2025-06-24 07:49:07.349432 :A8E75870:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:49:07.349432 :A8E75871:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:49:07.349433 :A8E75872:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:49:07.349433 :A8E75873:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:49:07.349433 :A8E75874:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:49:07.349946 :A8E75875:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:49:37.416564 :A8E762F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:50:07.437669 :A8E76CAC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:50:07.426 2025-06-24 07:50:07.437671 :A8E76CAD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16156 pso_num=25 pso_serial#=140 2025-06-24 07:50:07.437808 :A8E76CAE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16156 osp_idx=23 osp_ver=167022 osp_pg=0 (spawn #317210) 2025-06-24 07:50:07.437809 :A8E76CAF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:50:07.437809 :A8E76CB0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=145 time=3727150625 2025-06-24 07:50:07.437810 :A8E76CB1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:50:07.437810 :A8E76CB2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:50:07.437810 :A8E76CB3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:50:07.437811 :A8E76CB4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:50:07.437811 :A8E76CB5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:50:07.438289 :A8E76CB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:50:37.521936 :A8E7769F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:51:07.517707 :A8E7809E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:51:07.507 2025-06-24 07:51:07.517709 :A8E7809F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16372 pso_num=25 pso_serial#=141 2025-06-24 07:51:07.517828 :A8E780A0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16372 osp_idx=23 osp_ver=167023 osp_pg=0 (spawn #317212) 2025-06-24 07:51:07.517830 :A8E780A1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:51:07.517830 :A8E780A2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=146 time=3727210704 2025-06-24 07:51:07.517831 :A8E780A3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:51:07.517831 :A8E780A4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:51:07.517832 :A8E780A5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:51:07.517834 :A8E780A6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:51:07.517835 :A8E780A7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:51:07.518311 :A8E780A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:51:37.615096 :A8E78AA1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:52:07.587786 :A8E794B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:52:07.576 2025-06-24 07:52:07.587788 :A8E794B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18068 pso_num=25 pso_serial#=142 2025-06-24 07:52:07.587930 :A8E794B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18068 osp_idx=23 osp_ver=167024 osp_pg=0 (spawn #317215) 2025-06-24 07:52:07.587931 :A8E794B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:52:07.587931 :A8E794BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=3727270766 2025-06-24 07:52:07.587932 :A8E794BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:52:07.587932 :A8E794BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:52:07.587932 :A8E794BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:52:07.587933 :A8E794BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:52:07.587933 :A8E794BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:52:07.588441 :A8E794C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:52:37.661037 :A8E79EA5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:53:07.680390 :A8E7A84E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:53:07.670 2025-06-24 07:53:07.680392 :A8E7A84F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13508 pso_num=25 pso_serial#=143 2025-06-24 07:53:07.680512 :A8E7A850:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13508 osp_idx=23 osp_ver=167025 osp_pg=0 (spawn #317217) 2025-06-24 07:53:07.680513 :A8E7A851:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:53:07.680513 :A8E7A852:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=3727330860 2025-06-24 07:53:07.680514 :A8E7A853:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:53:07.680514 :A8E7A854:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:53:07.680515 :A8E7A855:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:53:07.680515 :A8E7A856:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:53:07.680515 :A8E7A857:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:53:07.681000 :A8E7A858:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:53:37.818418 :A8E7B272:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:54:07.779404 :A8E7BC48:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:54:07.768 2025-06-24 07:54:07.779406 :A8E7BC49:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14508 pso_num=25 pso_serial#=144 2025-06-24 07:54:07.779548 :A8E7BC4A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14508 osp_idx=23 osp_ver=167026 osp_pg=0 (spawn #317219) 2025-06-24 07:54:07.779548 :A8E7BC4B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:54:07.779549 :A8E7BC4C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=3727390969 2025-06-24 07:54:07.779550 :A8E7BC4D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:54:07.779550 :A8E7BC4E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:54:07.779550 :A8E7BC4F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:54:07.779551 :A8E7BC50:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:54:07.779551 :A8E7BC51:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:54:07.780036 :A8E7BC52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:54:37.843009 :A8E7C752:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:55:07.856625 :A8E7D132:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:55:07.846 2025-06-24 07:55:07.856627 :A8E7D133:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15484 pso_num=25 pso_serial#=145 2025-06-24 07:55:07.856758 :A8E7D134:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15484 osp_idx=23 osp_ver=167027 osp_pg=0 (spawn #317222) 2025-06-24 07:55:07.856759 :A8E7D135:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:55:07.856759 :A8E7D136:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=3727451047 2025-06-24 07:55:07.856760 :A8E7D137:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:55:07.856760 :A8E7D138:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:55:07.856761 :A8E7D139:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:55:07.856761 :A8E7D13A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:55:07.856761 :A8E7D13B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:55:07.857213 :A8E7D13C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:55:37.963637 :A8E7DB0C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:56:07.945140 :A8E7E4E0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:56:07.934 2025-06-24 07:56:07.945142 :A8E7E4E1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16620 pso_num=25 pso_serial#=146 2025-06-24 07:56:07.945272 :A8E7E4E2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16620 osp_idx=23 osp_ver=167028 osp_pg=0 (spawn #317224) 2025-06-24 07:56:07.945273 :A8E7E4E3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:56:07.945274 :A8E7E4E4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=3727511125 2025-06-24 07:56:07.945274 :A8E7E4E5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:56:07.945274 :A8E7E4E6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:56:07.945275 :A8E7E4E7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:56:07.945275 :A8E7E4E8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:56:07.945275 :A8E7E4E9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:56:07.945768 :A8E7E4EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:56:38.040631 :A8E7EED9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:57:08.031880 :A8E7F8C1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:57:08.020 2025-06-24 07:57:08.031882 :A8E7F8C2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14984 pso_num=25 pso_serial#=147 2025-06-24 07:57:08.032018 :A8E7F8C3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14984 osp_idx=23 osp_ver=167029 osp_pg=0 (spawn #317226) 2025-06-24 07:57:08.032019 :A8E7F8C4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:57:08.032019 :A8E7F8C5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=3727571219 2025-06-24 07:57:08.032020 :A8E7F8C6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:57:08.032020 :A8E7F8C7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:57:08.032020 :A8E7F8C8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:57:08.032021 :A8E7F8C9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:57:08.032021 :A8E7F8CA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:57:08.032515 :A8E7F8CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:57:38.126426 :A8E8030B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:58:08.126383 :A8E80CFF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:58:08.115 2025-06-24 07:58:08.126385 :A8E80D00:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16900 pso_num=25 pso_serial#=148 2025-06-24 07:58:08.126553 :A8E80D01:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16900 osp_idx=23 osp_ver=167030 osp_pg=0 (spawn #317228) 2025-06-24 07:58:08.126554 :A8E80D02:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:58:08.126554 :A8E80D03:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=3727631313 2025-06-24 07:58:08.126555 :A8E80D04:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:58:08.126556 :A8E80D05:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:58:08.126556 :A8E80D06:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:58:08.126556 :A8E80D07:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:58:08.126557 :A8E80D08:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:58:08.127088 :A8E80D09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:58:33.154162 :A8E8154F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-24 07:58:33.154199*:A8E81550:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1750723112 eid=16924542 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-24 07:58:33.154199*:A8E81551:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-06-24 07:58:33.154199*:A8E81552:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-06-24 07:58:33.154199*:A8E81553:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-06-24 07:58:33.154199*:A8E81554:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-06-24 07:58:33.154199*:A8E81555:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-06-24 07:58:33.154835 :A8E81556:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-24 07:58:33.160400 :A8E8155E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-24 07:58:33.161472 :A8E81566:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-24 07:58:33.161608 :A8E8156F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-24 07:59:03.257398 :A8E81F29:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 07:59:08.212987 :A8E820CC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 07:59:08.202 2025-06-24 07:59:08.212989 :A8E820CD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17584 pso_num=25 pso_serial#=149 2025-06-24 07:59:08.213121 :A8E820CE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17584 osp_idx=23 osp_ver=167031 osp_pg=0 (spawn #317230) 2025-06-24 07:59:08.213121 :A8E820CF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 07:59:08.213122 :A8E820D0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=3727691391 2025-06-24 07:59:08.213122 :A8E820D1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:59:08.213123 :A8E820D2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:59:08.213123 :A8E820D3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:59:08.213123 :A8E820D4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:59:08.213124 :A8E820D5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:59:08.213685 :A8E820D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 07:59:38.270679 :A8E82B69:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 08:00:08.305801 :A8E8355D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 08:00:08.294 2025-06-24 08:00:08.305804 :A8E8355E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16428 pso_num=25 pso_serial#=150 2025-06-24 08:00:08.305938 :A8E8355F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16428 osp_idx=23 osp_ver=167032 osp_pg=0 (spawn #317233) 2025-06-24 08:00:08.305939 :A8E83560:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 08:00:08.305939 :A8E83561:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=3727751485 2025-06-24 08:00:08.305940 :A8E83562:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 08:00:08.305940 :A8E83563:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 08:00:08.305940 :A8E83564:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 08:00:08.305941 :A8E83565:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 08:00:08.305941 :A8E83566:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 08:00:08.306418 :A8E83567:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 08:00:38.385162 :A8E84116:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 08:00:58.219485 :A8E880CD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 08:00:58.219 2025-06-24 08:00:58.219487 :A8E880CE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17924 pso_num=25 pso_serial#=151 2025-06-24 08:00:58.219649 :A8E880CF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17924 osp_idx=23 osp_ver=167033 osp_pg=0 (spawn #317237) 2025-06-24 08:00:58.219651 :A8E880D0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 08:00:58.219651 :A8E880D1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=3727801391 2025-06-24 08:00:58.219652 :A8E880D2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 08:00:58.219652 :A8E880D3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 08:00:58.219652 :A8E880D4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 08:00:58.219653 :A8E880D5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 08:00:58.219653 :A8E880D6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 08:00:58.220457 :A8E880D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 08:00:58.220495 :A8E880DD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-24 08:00:58.325650 :A8E8810F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-24 08:01:00.504391 :A8E88241:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-24 08:01:00.489 2025-06-24 08:01:00.504393 :A8E88242:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18080 pso_num=25 pso_serial#=152 2025-06-24 08:01:00.504531 :A8E88243:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18080 osp_idx=23 osp_ver=167034 osp_pg=0 (spawn #317239) 2025-06-24 08:01:00.504532 :A8E88244:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 08:01:00.504533 :A8E88245:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=3727803688 2025-06-24 08:01:00.504533 :A8E88246:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 08:01:00.504533 :A8E88247:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 08:01:00.504534 :A8E88248:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 08:01:00.504534 :A8E88249:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 08:01:00.504534 :A8E8824A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 08:01:00.505022 :A8E8824B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-24 08:01:00.505067 :A8E88251:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 18080, M002)