Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250602220006\orcl_j000_12132_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:37130M/63366M, Ph+PgF:43971M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12132, image: ORACLE.EXE (J000) *** 2025-06-02 22:00:06.275 *** SESSION ID:(201.52211) 2025-06-02 22:00:06.275 *** 2025-06-02 22:00:06.275 Process diagnostic dump for ORACLE.EXE (J000), OS id=12132, pid: 25, proc_ser: 186, sid: 201, sess_ser: 52211 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12132 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.240540 sec, exc=0.240540 sec, total=0.240540 sec wait times: max=0.500000 sec, heur=2.248149 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.507578 sec, exc=0.507578 sec, total=0.507578 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 52211 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-02 22:00:06.275 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12132, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-02 21:51:17.252903 :9F7A85C5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:51:17.252903 :9F7A85C6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:51:17.252904 :9F7A85C7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:51:17.252904 :9F7A85C8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:51:17.253404 :9F7A85C9: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-02 21:51:47.361585 :9F7A8F57:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:52:17.335491 :9F7A990E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:52:17.324 2025-06-02 21:52:17.335493 :9F7A990F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13524 pso_num=25 pso_serial#=177 2025-06-02 21:52:17.335632 :9F7A9910:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13524 osp_idx=23 osp_ver=141049 osp_pg=0 (spawn #245806) 2025-06-02 21:52:17.335632 :9F7A9911: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-02 21:52:17.335633 :9F7A9912:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=1876895969 2025-06-02 21:52:17.335633 :9F7A9913:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:52:17.335634 :9F7A9914:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:52:17.335634 :9F7A9915:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:52:17.335634 :9F7A9916:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:52:17.335635 :9F7A9917:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:52:17.336147 :9F7A9918: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-02 21:52:47.375977 :9F7AA2E7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:53:17.406178 :9F7AAC8C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:53:17.395 2025-06-02 21:53:17.406180 :9F7AAC8D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12924 pso_num=25 pso_serial#=178 2025-06-02 21:53:17.406313 :9F7AAC8E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12924 osp_idx=23 osp_ver=141050 osp_pg=0 (spawn #245808) 2025-06-02 21:53:17.406313 :9F7AAC8F: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-02 21:53:17.406314 :9F7AAC90:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=159 time=1876956047 2025-06-02 21:53:17.406314 :9F7AAC91:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:53:17.406314 :9F7AAC92:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:53:17.406314 :9F7AAC93:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:53:17.406315 :9F7AAC94:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:53:17.406315 :9F7AAC95:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:53:17.406785 :9F7AAC96: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-02 21:53:47.471461 :9F7AB677:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:53:52.460516 :9F7AB824:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:53:52.449 2025-06-02 21:53:52.460518 :9F7AB825:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12192 pso_num=25 pso_serial#=179 2025-06-02 21:53:52.460660 :9F7AB826:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12192 osp_idx=23 osp_ver=141051 osp_pg=0 (spawn #245810) 2025-06-02 21:53:52.460661 :9F7AB827: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-02 21:53:52.460662 :9F7AB828:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=160 time=1876991094 2025-06-02 21:53:52.460662 :9F7AB829:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:53:52.460662 :9F7AB82A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:53:52.460663 :9F7AB82B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:53:52.460663 :9F7AB82C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:53:52.460663 :9F7AB82D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:53:52.461147 :9F7AB82E: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-02 21:54:17.494096 :9F7AC05F: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-02 21:54:47.601386 :9F7ACAD6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:55:17.611421 :9F7AD4A2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:55:17.600 2025-06-02 21:55:17.611424 :9F7AD4A3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12876 pso_num=25 pso_serial#=180 2025-06-02 21:55:17.611608 :9F7AD4A4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12876 osp_idx=23 osp_ver=141052 osp_pg=0 (spawn #245812) 2025-06-02 21:55:17.611609 :9F7AD4A5: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-02 21:55:17.611609 :9F7AD4A6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=161 time=1877076250 2025-06-02 21:55:17.611610 :9F7AD4A7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:55:17.611611 :9F7AD4A8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:55:17.611611 :9F7AD4A9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:55:17.611611 :9F7AD4AA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:55:17.611612 :9F7AD4AB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:55:17.612103 :9F7AD4AC: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-02 21:55:47.718526 :9F7ADE95:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:56:17.711242 :9F7AE85F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:56:17.700 2025-06-02 21:56:17.711244 :9F7AE860:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8776 pso_num=25 pso_serial#=181 2025-06-02 21:56:17.711380 :9F7AE861:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8776 osp_idx=23 osp_ver=141053 osp_pg=0 (spawn #245814) 2025-06-02 21:56:17.711381 :9F7AE862: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-02 21:56:17.711382 :9F7AE863:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=162 time=1877136360 2025-06-02 21:56:17.711382 :9F7AE864:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:56:17.711383 :9F7AE865:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:56:17.711383 :9F7AE866:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:56:17.711383 :9F7AE867:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:56:17.711384 :9F7AE868:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:56:17.711872 :9F7AE869: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-02 21:56:47.818215 :9F7AF283:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:57:17.808990 :9F7AFC17:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:57:17.798 2025-06-02 21:57:17.808992 :9F7AFC18:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12448 pso_num=25 pso_serial#=182 2025-06-02 21:57:17.809120 :9F7AFC19:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12448 osp_idx=23 osp_ver=141054 osp_pg=0 (spawn #245817) 2025-06-02 21:57:17.809121 :9F7AFC1A: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-02 21:57:17.809121 :9F7AFC1B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=163 time=1877196454 2025-06-02 21:57:17.809122 :9F7AFC1C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:57:17.809122 :9F7AFC1D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:57:17.809122 :9F7AFC1E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:57:17.809123 :9F7AFC1F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:57:17.809123 :9F7AFC20:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:57:17.809602 :9F7AFC21: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-02 21:57:47.869141 :9F7B05E0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:58:17.908931 :9F7B0F9B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:58:17.898 2025-06-02 21:58:17.908934 :9F7B0F9C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13196 pso_num=25 pso_serial#=183 2025-06-02 21:58:17.909062 :9F7B0F9D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13196 osp_idx=23 osp_ver=141055 osp_pg=0 (spawn #245819) 2025-06-02 21:58:17.909063 :9F7B0F9E: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-02 21:58:17.909063 :9F7B0F9F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=1877256547 2025-06-02 21:58:17.909064 :9F7B0FA0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:58:17.909064 :9F7B0FA1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:58:17.909064 :9F7B0FA2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:58:17.909065 :9F7B0FA3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:58:17.909065 :9F7B0FA4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:58:17.909546 :9F7B0FA5: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-02 21:58:47.983585 :9F7B1944:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 21:59:18.009655 :9F7B22FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 21:59:18.009 2025-06-02 21:59:18.009657 :9F7B22FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12924 pso_num=25 pso_serial#=184 2025-06-02 21:59:18.009777 :9F7B22FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12924 osp_idx=23 osp_ver=141056 osp_pg=0 (spawn #245822) 2025-06-02 21:59:18.009778 :9F7B2300: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-02 21:59:18.009778 :9F7B2301:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=1877316641 2025-06-02 21:59:18.009779 :9F7B2302:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 21:59:18.009779 :9F7B2303:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 21:59:18.009779 :9F7B2304:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 21:59:18.009780 :9F7B2305:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 21:59:18.009780 :9F7B2306:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 21:59:18.010278 :9F7B2307: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-02 21:59:48.102449 :9F7B2D4A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 22:00:00.050587 :9F7B310F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 22:00:00.041 2025-06-02 22:00:00.050589 :9F7B3110:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14320 pso_num=25 pso_serial#=185 2025-06-02 22:00:00.050726 :9F7B3111:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14320 osp_idx=23 osp_ver=141057 osp_pg=0 (spawn #245824) 2025-06-02 22:00:00.050727 :9F7B3112: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-02 22:00:00.050727 :9F7B3113:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=1877358688 2025-06-02 22:00:00.050727 :9F7B3114:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 22:00:00.050728 :9F7B3115:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 22:00:00.050728 :9F7B3116:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 22:00:00.050728 :9F7B3117:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 22:00:00.050729 :9F7B3118:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 22:00:00.051228 :9F7B3119: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-02 22:00:00.051276 :9F7B3120: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-02 22:00:00.051354 :9F7B3134:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-02 22:00:00.055265 :9F7B3143: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-02 22:00:00.056462 :9F7B314B: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-02 22:00:00.056487 :9F7B314E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-02 22:00:00.056527 :9F7B3159:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-02 22:00:00.058331 :9F7B3164: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-02 22:00:00.058360 :9F7B3167:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-02 22:00:00.058397 :9F7B3172:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-02 22:00:00.058859 :9F7B317D: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-02 22:00:00.061313 :9F7B3185: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-02 22:00:00.066620 :9F7B318D: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-02 22:00:00.072059 :9F7B3195: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-02 22:00:00.073598 :9F7B31F2: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-02 22:00:00.074480 :9F7B31FA: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-02 22:00:00.076433 :9F7B3202: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-02 22:00:00.077199 :9F7B320A: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-02 22:00:00.078453 :9F7B3212: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-02 22:00:00.081309 :9F7B321A: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-02 22:00:00.084472 :9F7B3222:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-02 22:00:00.084795 :9F7B3231: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-02 22:00:00.084835 :9F7B3234:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-02 22:00:00.084847 :9F7B3235:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-02 22:00:00.086662 :9F7B323B: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-02 22:00:00.086700 :9F7B323E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-02 22:00:00.086710 :9F7B323F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-02 22:00:00.088529 :9F7B3245: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-02 22:00:00.088587 :9F7B3248:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-02 22:00:00.088599 :9F7B3249:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-02 22:00:00.088766 :9F7B324F: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-02 22:00:00.088780 :9F7B3252:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-02 22:00:00.088817 :9F7B325D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-02 22:00:00.090331 :9F7B3268:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-02 22:00:02.057001 :9F7B32E9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-02 22:00:02.056 2025-06-02 22:00:02.057003 :9F7B32EA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12132 pso_num=25 pso_serial#=186 2025-06-02 22:00:02.057136 :9F7B32EB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12132 osp_idx=23 osp_ver=141058 osp_pg=0 (spawn #245825) 2025-06-02 22:00:02.057136 :9F7B32EC: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-02 22:00:02.057137 :9F7B32ED:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=167 time=1877360704 2025-06-02 22:00:02.057137 :9F7B32EE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-02 22:00:02.057138 :9F7B32EF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-02 22:00:02.057138 :9F7B32F0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-02 22:00:02.057138 :9F7B32F1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-02 22:00:02.057139 :9F7B32F2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-02 22:00:02.057671 :9F7B32F3: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-02 22:00:02.063079 :9F7B3312: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-02 22:00:02.063127 :9F7B331B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-02 22:00:02.074070 :9F7B3375: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-02 22:00:02.079426 :9F7B339E: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-02 22:00:02.080241 :9F7B33C5: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-02 22:00:02.085705 :9F7B33EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-02 22:00:02.085854 :9F7B33F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-02 22:00:02.086582 :9F7B3401: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-02 22:00:02.086769 :9F7B3409:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-02 22:00:02.088268 :9F7B340A: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-02 22:00:02.088310 :9F7B340D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-02 22:00:02.088404 :9F7B341A: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-02 22:00:02.187213 :9F7B3684: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-02 22:00:02.189287 :9F7B3687: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-02 22:00:02.189329 :9F7B368A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-02 22:00:02.191779 :9F7B3690:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-02 22:00:02.195026 :9F7B3691: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-02 22:00:02.195107 :9F7B3694:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-02 22:00:02.195205 :9F7B36A1: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-02 22:00:04.015516 :9F7B3A6B: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-02 22:00:04.017735 :9F7B3A72: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-02 22:00:04.017776 :9F7B3A75:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-02 22:00:04.027758 :9F7B3AAD: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-02 22:00:04.028541 :9F7B3AB5: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-02 22:00:04.029472 :9F7B3ABD: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-02 22:00:04.029679 :9F7B3AC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-02 22:00:04.031260 :9F7B3ACB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-06-02 22:00:04.031857 :9F7B3AD9: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-02 22:00:04.031900 :9F7B3ADC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-02 22:00:04.031945 :9F7B3AE9: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 12132, J000)