Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250619220006\orcl_j001_15704_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250619220006\orcl_j001_15704_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:36486M/63366M, Ph+PgF:42890M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15704, image: ORACLE.EXE (J001) *** 2025-06-19 22:00:06.286 *** SESSION ID:(201.33213) 2025-06-19 22:00:06.286 *** 2025-06-19 22:00:06.286 Process diagnostic dump for ORACLE.EXE (J001), OS id=15704, pid: 25, proc_ser: 176, sid: 201, sess_ser: 33213 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15704 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) 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.086969 sec, exc=0.086969 sec, total=0.086969 sec wait times: max=0.500000 sec, heur=2.086061 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.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.000008 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.499998 sec, exc=0.499998 sec, total=0.499998 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=1 seq_num=2 snap_id=1 wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 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.488957 sec, exc=0.488957 sec, total=0.488957 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 33213 --------------------------------------------------- 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-19 22:00:06.286 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15704, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-19 21:50:03.240921 :A6F1B687:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:50:33.248552 :A6F1C042:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:50:33.238 2025-06-19 21:50:33.248555 :A6F1C043:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15900 pso_num=25 pso_serial#=167 2025-06-19 21:50:33.248694 :A6F1C044:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15900 osp_idx=23 osp_ver=162459 osp_pg=0 (spawn #302463) 2025-06-19 21:50:33.248695 :A6F1C045: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-19 21:50:33.248695 :A6F1C046:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=66 time=3345576438 2025-06-19 21:50:33.248696 :A6F1C047:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:50:33.248696 :A6F1C048:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:50:33.248696 :A6F1C049:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:50:33.248697 :A6F1C04A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:50:33.248697 :A6F1C04B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:50:33.249261 :A6F1C04C: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-19 21:51:03.347939 :A6F1CAA7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:51:33.338209 :A6F1D468:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:51:33.327 2025-06-19 21:51:33.338211 :A6F1D469:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14312 pso_num=25 pso_serial#=168 2025-06-19 21:51:33.338347 :A6F1D46A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14312 osp_idx=23 osp_ver=162460 osp_pg=0 (spawn #302466) 2025-06-19 21:51:33.338348 :A6F1D46B: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-19 21:51:33.338348 :A6F1D46C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=67 time=3345636516 2025-06-19 21:51:33.338349 :A6F1D46D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:51:33.338349 :A6F1D46E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:51:33.338349 :A6F1D46F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:51:33.338350 :A6F1D470:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:51:33.338350 :A6F1D471:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:51:33.338823 :A6F1D472: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-19 21:52:03.396512 :A6F1DE58:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:52:33.399907 :A6F1E81F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:52:33.388 2025-06-19 21:52:33.399909 :A6F1E820:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17396 pso_num=25 pso_serial#=169 2025-06-19 21:52:33.400046 :A6F1E821:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17396 osp_idx=23 osp_ver=162461 osp_pg=0 (spawn #302468) 2025-06-19 21:52:33.400047 :A6F1E822: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-19 21:52:33.400048 :A6F1E823:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=68 time=3345696579 2025-06-19 21:52:33.400048 :A6F1E824:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:52:33.400049 :A6F1E825:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:52:33.400049 :A6F1E826:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:52:33.400049 :A6F1E827:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:52:33.400050 :A6F1E828:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:52:33.400523 :A6F1E829: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-19 21:53:03.504808 :A6F1F238:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:53:33.475600 :A6F1FC0A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:53:33.464 2025-06-19 21:53:33.475602 :A6F1FC0B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18244 pso_num=25 pso_serial#=170 2025-06-19 21:53:33.475726 :A6F1FC0C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18244 osp_idx=23 osp_ver=162462 osp_pg=0 (spawn #302470) 2025-06-19 21:53:33.475726 :A6F1FC0D: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-19 21:53:33.475727 :A6F1FC0E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=69 time=3345756657 2025-06-19 21:53:33.475727 :A6F1FC0F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:53:33.475728 :A6F1FC10:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:53:33.475728 :A6F1FC11:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:53:33.475728 :A6F1FC12:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:53:33.475729 :A6F1FC13:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:53:33.476226 :A6F1FC14: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-19 21:54:03.552892 :A6F205D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:54:33.563284 :A6F20F91:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:54:33.552 2025-06-19 21:54:33.563286 :A6F20F92:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18096 pso_num=25 pso_serial#=171 2025-06-19 21:54:33.563412 :A6F20F93:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18096 osp_idx=23 osp_ver=162463 osp_pg=0 (spawn #302472) 2025-06-19 21:54:33.563413 :A6F20F94: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-19 21:54:33.563413 :A6F20F95:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=70 time=3345816750 2025-06-19 21:54:33.563414 :A6F20F96:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:54:33.563414 :A6F20F97:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:54:33.563415 :A6F20F98:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:54:33.563415 :A6F20F99:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:54:33.563416 :A6F20F9A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:54:33.563921 :A6F20F9B: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-19 21:55:03.663050 :A6F21A72:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:55:33.658806 :A6F2241D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:55:33.648 2025-06-19 21:55:33.658808 :A6F2241E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17516 pso_num=25 pso_serial#=172 2025-06-19 21:55:33.658945 :A6F2241F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17516 osp_idx=23 osp_ver=162464 osp_pg=0 (spawn #302474) 2025-06-19 21:55:33.658946 :A6F22420: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-19 21:55:33.658946 :A6F22421:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=71 time=3345876844 2025-06-19 21:55:33.658947 :A6F22422:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:55:33.658947 :A6F22423:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:55:33.658948 :A6F22424:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:55:33.658948 :A6F22425:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:55:33.658948 :A6F22426:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:55:33.659436 :A6F22427: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-19 21:56:03.712087 :A6F22E14:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:56:33.727248 :A6F237D8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:56:33.716 2025-06-19 21:56:33.727250 :A6F237D9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17604 pso_num=25 pso_serial#=173 2025-06-19 21:56:33.727380 :A6F237DA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17604 osp_idx=23 osp_ver=162465 osp_pg=0 (spawn #302476) 2025-06-19 21:56:33.727381 :A6F237DB: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-19 21:56:33.727381 :A6F237DC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=72 time=3345936907 2025-06-19 21:56:33.727381 :A6F237DD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:56:33.727382 :A6F237DE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:56:33.727382 :A6F237DF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:56:33.727383 :A6F237E0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:56:33.727383 :A6F237E1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:56:33.727856 :A6F237E2: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-19 21:57:03.791998 :A6F2419F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:57:33.827858 :A6F24B75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:57:33.816 2025-06-19 21:57:33.827860 :A6F24B76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14460 pso_num=25 pso_serial#=174 2025-06-19 21:57:33.828015 :A6F24B77:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14460 osp_idx=23 osp_ver=162466 osp_pg=0 (spawn #302478) 2025-06-19 21:57:33.828016 :A6F24B78: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-19 21:57:33.828016 :A6F24B79:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=73 time=3345997016 2025-06-19 21:57:33.828017 :A6F24B7A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:57:33.828018 :A6F24B7B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:57:33.828018 :A6F24B7C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:57:33.828019 :A6F24B7D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:57:33.828019 :A6F24B7E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:57:33.828499 :A6F24B7F: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-19 21:58:03.908318 :A6F25608:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:58:33.916397 :A6F25FB1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:58:33.905 2025-06-19 21:58:33.916399 :A6F25FB2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18008 pso_num=25 pso_serial#=175 2025-06-19 21:58:33.916529 :A6F25FB3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18008 osp_idx=23 osp_ver=162467 osp_pg=0 (spawn #302481) 2025-06-19 21:58:33.916530 :A6F25FB4: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-19 21:58:33.916530 :A6F25FB5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=74 time=3346057094 2025-06-19 21:58:33.916530 :A6F25FB6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:58:33.916531 :A6F25FB7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:58:33.916531 :A6F25FB8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:58:33.916531 :A6F25FB9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:58:33.916532 :A6F25FBA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:58:33.917033 :A6F25FBB: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-19 21:58:58.926616 :A6F2680E: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-19 21:58:58.926649*:A6F2680F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1750341538 eid=16917534 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-19 21:58:58.926649*:A6F26810:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-06-19 21:58:58.926649*:A6F26811:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-06-19 21:58:58.926649*:A6F26812:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-06-19 21:58:58.926649*:A6F26813:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-06-19 21:58:58.926649*:A6F26814:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-06-19 21:58:58.927220 :A6F26815: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-19 21:58:58.932605 :A6F2681D: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-19 21:58:58.933584 :A6F26825: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-19 21:58:58.933731 :A6F2682E: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-19 21:59:28.984428 :A6F271E9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-19 21:59:33.974386 :A6F273AA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-19 21:59:33.963 2025-06-19 21:59:33.974388 :A6F273AB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15704 pso_num=25 pso_serial#=176 2025-06-19 21:59:33.974513 :A6F273AC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15704 osp_idx=23 osp_ver=162468 osp_pg=0 (spawn #302483) 2025-06-19 21:59:33.974514 :A6F273AD: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-19 21:59:33.974515 :A6F273AE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=75 time=3346117157 2025-06-19 21:59:33.974515 :A6F273AF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:59:33.974515 :A6F273B0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:59:33.974516 :A6F273B1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:59:33.974516 :A6F273B2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:59:33.974516 :A6F273B3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:59:33.975007 :A6F273B4: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-19 22:00:02.041896 :A6F27F03: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-19 22:00:02.041896*:A6F27F12:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB898 estart=1750341601 eid=16782640 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-19 22:00:02.041896*:A6F27F13:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 pubTabIdxCnt=0 2025-06-19 22:00:02.041896*:A6F27F14:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB898 2025-06-19 22:00:02.041896*:A6F27F15:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB898 2025-06-19 22:00:02.041896*:A6F27F16:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 xsc->flg4=65792 flags=2 2025-06-19 22:00:02.041896*:A6F27F17:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB898 2025-06-19 22:00:02.047474 :A6F27F26: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-19 22:00:02.047518 :A6F27F2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-19 22:00:02.052440 :A6F27F4F:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-19 22:00:02.053042 :A6F27F63:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 bd11120000000000 2025-06-19 22:00:02.054790 :A6F27F79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.054863 :A6F27F87:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.054873 :A6F27F8D:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-06-19 22:00:02.054878 :A6F27F8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.054906 :A6F27F9B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.055644 :A6F27FA6:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 228885339) 2025-06-19 22:00:02.055648 :A6F27FA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.055671 :A6F27FB2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.055680 :A6F27FBF:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65561 1) 2025-06-19 22:00:02.055682 :A6F27FC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.055708 :A6F27FCC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.055712 :A6F27FD7:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65561) 2025-06-19 22:00:02.055715 :A6F27FD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.055764 :A6F27FF7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.059392 :A6F2826A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.059415 :A6F2826F:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-19 22:00:02.060290 :A6F2828B:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 be11120000000000 2025-06-19 22:00:02.060657 :A6F2828C:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 228885339) 2025-06-19 22:00:02.060662 :A6F2828D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.060689 :A6F28298:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 22:00:02.069138 :A6F282EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-19 22:00:02.070872 :A6F282EB: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-19 22:00:02.070930 :A6F282EE: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-19 22:00:02.071035 :A6F282FB: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-19 22:00:02.167210 :A6F28595: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-19 22:00:04.198513 :A6F28A68: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-19 22:00:04.198513*:A6F28A6E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1750341603 eid=16782646 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-19 22:00:04.198513*:A6F28A6F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-06-19 22:00:04.198513*:A6F28A70:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-06-19 22:00:04.198513*:A6F28A71:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-06-19 22:00:04.198513*:A6F28A72:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-06-19 22:00:04.198513*:A6F28A73:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-06-19 22:00:04.200113 :A6F28A74: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-19 22:00:04.200144 :A6F28A77:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-19 22:00:04.205448 :A6F28A7D: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-19 22:00:04.205920 :A6F28A85: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-19 22:00:04.206391 :A6F28A8D: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-19 22:00:04.206570 :A6F28A95:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-19 22:00:04.206883 :A6F28A96: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-19 22:00:04.207656 :A6F28A9E: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-19 22:00:04.207679 :A6F28AA1: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-19 22:00:04.207745 :A6F28AAE: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: 15704, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de