Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250827220006\orcl_j000_21864_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250827220006\orcl_j000_21864_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:35157M/63366M, Ph+PgF:40155M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 21864, image: ORACLE.EXE (J000) *** 2025-08-27 22:00:06.664 *** SESSION ID:(201.52955) 2025-08-27 22:00:06.664 *** 2025-08-27 22:00:06.664 Process diagnostic dump for ORACLE.EXE (J000), OS id=21864, pid: 25, proc_ser: 52, sid: 201, sess_ser: 52955 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21864 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.133926 sec, exc=0.133926 sec, total=0.133926 sec wait times: max=0.500000 sec, heur=2.630540 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000005 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510127 sec, exc=0.510127 sec, total=0.510127 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499975 sec, exc=0.499975 sec, total=0.499975 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486488 sec, exc=0.486488 sec, total=0.486488 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 52955 --------------------------------------------------- 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-08-27 22:00:06.664 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 21864, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-27 21:51:39.543463 :C57DFF78:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:52:09.540625 :C57E09AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:52:09.529 2025-08-27 21:52:09.540627 :C57E09AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7652 pso_num=25 pso_serial#=43 2025-08-27 21:52:09.540754 :C57E09AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7652 osp_idx=23 osp_ver=262040 osp_pg=0 (spawn #532468) 2025-08-27 21:52:09.540756 :C57E09AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:52:09.540756 :C57E09AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=76 time=717180846 2025-08-27 21:52:09.540756 :C57E09B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:52:09.540757 :C57E09B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:52:09.540757 :C57E09B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:52:09.540757 :C57E09B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:52:09.540757 :C57E09B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:52:09.541252 :C57E09B5: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-08-27 21:52:39.594063 :C57E135F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:53:09.642902 :C57E1CCF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:53:09.632 2025-08-27 21:53:09.642904 :C57E1CD0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22784 pso_num=25 pso_serial#=44 2025-08-27 21:53:09.643036 :C57E1CD1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22784 osp_idx=23 osp_ver=262041 osp_pg=0 (spawn #532470) 2025-08-27 21:53:09.643037 :C57E1CD2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:53:09.643037 :C57E1CD3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=77 time=717240940 2025-08-27 21:53:09.643038 :C57E1CD4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:53:09.643038 :C57E1CD5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:53:09.643038 :C57E1CD6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:53:09.643039 :C57E1CD7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:53:09.643039 :C57E1CD8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:53:09.643526 :C57E1CD9: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-08-27 21:53:39.725054 :C57E2638:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:54:09.788990 :C57E2FE3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:54:09.777 2025-08-27 21:54:09.788992 :C57E2FE4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21904 pso_num=25 pso_serial#=45 2025-08-27 21:54:09.789106 :C57E2FE5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21904 osp_idx=23 osp_ver=262042 osp_pg=0 (spawn #532472) 2025-08-27 21:54:09.789107 :C57E2FE6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:54:09.789107 :C57E2FE7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=78 time=717301080 2025-08-27 21:54:09.789108 :C57E2FE8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:54:09.789108 :C57E2FE9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:54:09.789109 :C57E2FEA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:54:09.789109 :C57E2FEB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:54:09.789109 :C57E2FEC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:54:09.789588 :C57E2FED: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-08-27 21:54:39.865420 :C57E3AE3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:55:09.886934 :C57E4470:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:55:09.876 2025-08-27 21:55:09.886936 :C57E4471:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22804 pso_num=25 pso_serial#=46 2025-08-27 21:55:09.887071 :C57E4472:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22804 osp_idx=23 osp_ver=262043 osp_pg=0 (spawn #532474) 2025-08-27 21:55:09.887071 :C57E4473:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:55:09.887072 :C57E4474:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=79 time=717361190 2025-08-27 21:55:09.887073 :C57E4475:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:55:09.887073 :C57E4476:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:55:09.887073 :C57E4477:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:55:09.887073 :C57E4478:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:55:09.887074 :C57E4479:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:55:09.887545 :C57E447A: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-08-27 21:55:39.955883 :C57E4E46:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:56:10.003786 :C57E57A3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:56:09.993 2025-08-27 21:56:10.003787 :C57E57A4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23032 pso_num=25 pso_serial#=47 2025-08-27 21:56:10.003925 :C57E57A5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23032 osp_idx=23 osp_ver=262044 osp_pg=0 (spawn #532477) 2025-08-27 21:56:10.003926 :C57E57A6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:56:10.003926 :C57E57A7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=80 time=717421299 2025-08-27 21:56:10.003927 :C57E57A8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:56:10.003927 :C57E57A9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:56:10.003927 :C57E57AA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:56:10.003928 :C57E57AB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:56:10.003928 :C57E57AC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:56:10.004422 :C57E57AD: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-08-27 21:56:40.044672 :C57E6171:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:57:10.064896 :C57E6B5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:57:10.054 2025-08-27 21:57:10.064898 :C57E6B60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13240 pso_num=25 pso_serial#=48 2025-08-27 21:57:10.065017 :C57E6B61:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13240 osp_idx=23 osp_ver=262045 osp_pg=0 (spawn #532479) 2025-08-27 21:57:10.065018 :C57E6B62:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:57:10.065018 :C57E6B63:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=81 time=717481362 2025-08-27 21:57:10.065019 :C57E6B64:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:57:10.065019 :C57E6B65:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:57:10.065019 :C57E6B66:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:57:10.065020 :C57E6B67:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:57:10.065020 :C57E6B68:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:57:10.065497 :C57E6B69: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-08-27 21:57:40.124351 :C57E7576:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:57:55.127658 :C57E7A5B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:57:55.117 2025-08-27 21:57:55.127660 :C57E7A5C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22952 pso_num=25 pso_serial#=49 2025-08-27 21:57:55.127795 :C57E7A5D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22952 osp_idx=23 osp_ver=262046 osp_pg=0 (spawn #532481) 2025-08-27 21:57:55.127795 :C57E7A5E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:57:55.127796 :C57E7A5F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=82 time=717526424 2025-08-27 21:57:55.127796 :C57E7A60:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:57:55.127796 :C57E7A61:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:57:55.127797 :C57E7A62:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:57:55.127797 :C57E7A63:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:57:55.127797 :C57E7A64:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:57:55.128288 :C57E7A65: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-08-27 21:58:10.146193 :C57E7F58: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-08-27 21:58:40.228159 :C57E8942:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 21:59:10.231859 :C57E92BB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 21:59:10.220 2025-08-27 21:59:10.231861 :C57E92BC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20312 pso_num=25 pso_serial#=50 2025-08-27 21:59:10.231980 :C57E92BD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20312 osp_idx=23 osp_ver=262047 osp_pg=0 (spawn #532484) 2025-08-27 21:59:10.231981 :C57E92BE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 21:59:10.231981 :C57E92BF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=83 time=717601533 2025-08-27 21:59:10.231982 :C57E92C0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 21:59:10.231982 :C57E92C1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 21:59:10.231982 :C57E92C2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 21:59:10.231983 :C57E92C3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 21:59:10.231983 :C57E92C4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 21:59:10.232499 :C57E92C5: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-08-27 21:59:40.323289 :C57E9CA6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 22:00:00.027492 :C57EA304:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 22:00:00.018 2025-08-27 22:00:00.027494 :C57EA305:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23412 pso_num=25 pso_serial#=51 2025-08-27 22:00:00.027626 :C57EA306:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23412 osp_idx=23 osp_ver=262048 osp_pg=0 (spawn #532486) 2025-08-27 22:00:00.027627 :C57EA307:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 22:00:00.027628 :C57EA308:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=84 time=717651330 2025-08-27 22:00:00.027628 :C57EA309:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 22:00:00.027628 :C57EA30A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 22:00:00.027628 :C57EA30B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 22:00:00.027628 :C57EA30C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 22:00:00.027629 :C57EA30D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 22:00:00.028131 :C57EA30E: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-08-27 22:00:00.028177 :C57EA315: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-08-27 22:00:00.028252 :C57EA329: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-08-27 22:00:00.032221 :C57EA338: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-08-27 22:00:00.033466 :C57EA340: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-08-27 22:00:00.033491 :C57EA343: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-08-27 22:00:00.033530 :C57EA34F: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-08-27 22:00:00.035352 :C57EA359: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-08-27 22:00:00.035381 :C57EA35C: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-08-27 22:00:00.035423 :C57EA367: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-08-27 22:00:00.035875 :C57EA372: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-08-27 22:00:00.038426 :C57EA37A: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-08-27 22:00:00.043756 :C57EA382: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-08-27 22:00:00.049269 :C57EA38A: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-08-27 22:00:00.050655 :C57EA392: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-08-27 22:00:00.053699 :C57EA39A: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-08-27 22:00:00.054987 :C57EA3A2: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-08-27 22:00:00.055941 :C57EA3AA: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-08-27 22:00:00.058613 :C57EA3B2: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-08-27 22:00:00.062293 :C57EA3BA: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-08-27 22:00:00.062653 :C57EA3C9: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-08-27 22:00:00.062700 :C57EA3CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-27 22:00:00.062715 :C57EA3CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-27 22:00:00.064673 :C57EA3D3: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-08-27 22:00:00.064718 :C57EA3D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-27 22:00:00.064730 :C57EA3D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-27 22:00:00.066777 :C57EA3DD: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-08-27 22:00:00.066824 :C57EA3E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-27 22:00:00.066836 :C57EA3E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-27 22:00:00.067020 :C57EA3E7: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-08-27 22:00:00.067033 :C57EA3EA: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-08-27 22:00:00.067071 :C57EA3F5: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-08-27 22:00:00.068581 :C57EA400:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-27 22:00:00.305407 :C57EA469:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-27 22:00:00.300 2025-08-27 22:00:00.305409 :C57EA46A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21864 pso_num=25 pso_serial#=52 2025-08-27 22:00:00.305547 :C57EA46B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21864 osp_idx=23 osp_ver=262049 osp_pg=0 (spawn #532487) 2025-08-27 22:00:00.305547 :C57EA46C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 22:00:00.305548 :C57EA46D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=717651612 2025-08-27 22:00:00.305548 :C57EA46E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 22:00:00.305549 :C57EA46F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 22:00:00.305549 :C57EA470:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 22:00:00.305549 :C57EA471:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 22:00:00.305550 :C57EA472:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 22:00:00.306075 :C57EA473: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-08-27 22:00:00.311429 :C57EA492: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-08-27 22:00:00.311470 :C57EA496:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-27 22:00:00.322874 :C57EA4F3: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-08-27 22:00:00.328102 :C57EA523: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-08-27 22:00:00.328861 :C57EA540: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-08-27 22:00:00.334317 :C57EA55B: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-08-27 22:00:00.334470 :C57EA56A: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-08-27 22:00:00.335350 :C57EA57E: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-08-27 22:00:00.335565 :C57EA586:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-27 22:00:00.337124 :C57EA5B4: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-08-27 22:00:00.337172 :C57EA5B7: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-08-27 22:00:00.337256 :C57EA5C4: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-08-27 22:00:00.440778 :C57EA7F2: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-08-27 22:00:00.442844 :C57EA7F7: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-08-27 22:00:00.442880 :C57EA7FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-27 22:00:00.445324 :C57EA800:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-27 22:00:00.448540 :C57EA801: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-08-27 22:00:00.448619 :C57EA804: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-08-27 22:00:00.448703 :C57EA811: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-08-27 22:00:04.029603 :C57EACD2: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-08-27 22:00:04.029603*:C57EACD6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B6C718 estart=1756303203 eid=16786891 ctx=0x000000077FD6A000 cwh=0x0000000026B60068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-27 22:00:04.029603*:C57EACD7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B6C718 curCtx=0x0000000026B68938 pubTabIdxCnt=0 2025-08-27 22:00:04.029603*:C57EACD8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B6C718 2025-08-27 22:00:04.029603*:C57EACD9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B6C718 2025-08-27 22:00:04.029603*:C57EACDA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B6C718 curCtx=0x0000000026B68938 xsc->flg4=65792 flags=2 2025-08-27 22:00:04.029603*:C57EACDB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B6C718 2025-08-27 22:00:04.031739 :C57EACDC: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-08-27 22:00:04.031792 :C57EACDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-27 22:00:04.038391 :C57EACE5: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-08-27 22:00:04.039108 :C57EACED: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-08-27 22:00:04.039915 :C57EACF5: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-08-27 22:00:04.040120 :C57EACFD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-27 22:00:04.041530 :C57EACFE: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-08-27 22:00:04.041570 :C57EAD01: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-08-27 22:00:04.041650 :C57EAD0D: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: 21864, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de