Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614180601\orcl_j001_16160_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614180601\orcl_j001_16160_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:36657M/63366M, Ph+PgF:43112M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16160, image: ORACLE.EXE (J001) *** 2025-06-14 18:06:01.167 *** SESSION ID:(201.23039) 2025-06-14 18:06:01.167 *** 2025-06-14 18:06:01.167 Process diagnostic dump for ORACLE.EXE (J001), OS id=16160, pid: 25, proc_ser: 101, sid: 201, sess_ser: 23039 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16160 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.178045 sec, exc=0.178045 sec, total=0.178045 sec wait times: max=0.500000 sec, heur=5.173336 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499944 sec, exc=0.499944 sec, total=0.499944 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.503834 sec, exc=0.503834 sec, total=0.503834 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=5 seq_num=6 snap_id=1 wait times: snap=0.500127 sec, exc=0.500127 sec, total=0.500127 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000012 sec of elapsed time 7: 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.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.491304 sec, exc=0.491304 sec, total=0.491304 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 23039 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 18:05:55 - 18:06:01] idle wait at each sample [session created at: 18:05:55] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 18:06:01.167 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16160, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 17:56:49.087361 :A4AAA4C2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:56:49.087362 :A4AAA4C3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:56:49.087362 :A4AAA4C4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:56:49.087362 :A4AAA4C5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:56:49.087896 :A4AAA4C6: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-14 17:57:19.143659 :A4AAAEAA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 17:57:49.147505 :A4AAB896:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 17:57:49.136 2025-06-14 17:57:49.147507 :A4AAB897:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16636 pso_num=25 pso_serial#=93 2025-06-14 17:57:49.147638 :A4AAB898:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16636 osp_idx=23 osp_ver=157540 osp_pg=0 (spawn #285258) 2025-06-14 17:57:49.147638 :A4AAB899: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-14 17:57:49.147639 :A4AAB89A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=2899612329 2025-06-14 17:57:49.147639 :A4AAB89B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:57:49.147640 :A4AAB89C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:57:49.147640 :A4AAB89D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:57:49.147640 :A4AAB89E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:57:49.147640 :A4AAB89F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:57:49.148109 :A4AAB8A0: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-14 17:58:14.166095 :A4AAC192: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-14 17:58:44.255760 :A4AACB5D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 17:58:49.222752 :A4AACD17:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 17:58:49.211 2025-06-14 17:58:49.222753 :A4AACD18:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3624 pso_num=25 pso_serial#=94 2025-06-14 17:58:49.222883 :A4AACD19:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3624 osp_idx=23 osp_ver=157541 osp_pg=0 (spawn #285260) 2025-06-14 17:58:49.222884 :A4AACD1A: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-14 17:58:49.222885 :A4AACD1B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=2899672407 2025-06-14 17:58:49.222885 :A4AACD1C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:58:49.222885 :A4AACD1D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:58:49.222886 :A4AACD1E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:58:49.222886 :A4AACD1F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:58:49.222886 :A4AACD20:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:58:49.223394 :A4AACD21: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-14 17:59:19.315548 :A4AAD6E2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 17:59:49.328876 :A4AAE08D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 17:59:49.317 2025-06-14 17:59:49.328878 :A4AAE08E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16548 pso_num=25 pso_serial#=95 2025-06-14 17:59:49.329016 :A4AAE08F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16548 osp_idx=23 osp_ver=157542 osp_pg=0 (spawn #285262) 2025-06-14 17:59:49.329017 :A4AAE090: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-14 17:59:49.329017 :A4AAE091:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=2899732516 2025-06-14 17:59:49.329018 :A4AAE092:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:59:49.329018 :A4AAE093:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:59:49.329019 :A4AAE094:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:59:49.329019 :A4AAE095:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:59:49.329019 :A4AAE096:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:59:49.329506 :A4AAE097: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-14 18:00:19.388304 :A4AAEAA4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 18:00:49.415528 :A4AAF54B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 18:00:49.404 2025-06-14 18:00:49.415530 :A4AAF54C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15628 pso_num=25 pso_serial#=96 2025-06-14 18:00:49.415664 :A4AAF54D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15628 osp_idx=23 osp_ver=157543 osp_pg=0 (spawn #285264) 2025-06-14 18:00:49.415665 :A4AAF54E: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-14 18:00:49.415665 :A4AAF54F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=2899792594 2025-06-14 18:00:49.415665 :A4AAF550:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:00:49.415666 :A4AAF551:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:00:49.415666 :A4AAF552:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:00:49.415666 :A4AAF553:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:00:49.415667 :A4AAF554:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:00:49.416140 :A4AAF555: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-14 18:01:19.481047 :A4AB01CF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 18:01:49.503979 :A4AB0BC5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 18:01:49.492 2025-06-14 18:01:49.503981 :A4AB0BC6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16976 pso_num=25 pso_serial#=97 2025-06-14 18:01:49.504113 :A4AB0BC7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16976 osp_idx=23 osp_ver=157544 osp_pg=0 (spawn #285270) 2025-06-14 18:01:49.504113 :A4AB0BC8: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-14 18:01:49.504114 :A4AB0BC9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=2899852688 2025-06-14 18:01:49.504114 :A4AB0BCA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:01:49.504114 :A4AB0BCB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:01:49.504115 :A4AB0BCC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:01:49.504115 :A4AB0BCD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:01:49.504115 :A4AB0BCE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:01:49.504625 :A4AB0BCF: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-14 18:02:19.593774 :A4AB15A6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 18:02:49.602358 :A4AB1F7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 18:02:49.591 2025-06-14 18:02:49.602360 :A4AB1F7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13828 pso_num=25 pso_serial#=98 2025-06-14 18:02:49.602494 :A4AB1F7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13828 osp_idx=23 osp_ver=157545 osp_pg=0 (spawn #285272) 2025-06-14 18:02:49.602495 :A4AB1F7E: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-14 18:02:49.602496 :A4AB1F7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=2899912782 2025-06-14 18:02:49.602496 :A4AB1F80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:02:49.602496 :A4AB1F81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:02:49.602497 :A4AB1F82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:02:49.602497 :A4AB1F83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:02:49.602498 :A4AB1F84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:02:49.602984 :A4AB1F85: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-14 18:03:19.690101 :A4AB2A9B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 18:03:49.688849 :A4AB3490:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 18:03:49.677 2025-06-14 18:03:49.688851 :A4AB3491:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13156 pso_num=25 pso_serial#=99 2025-06-14 18:03:49.688990 :A4AB3492:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13156 osp_idx=23 osp_ver=157546 osp_pg=0 (spawn #285275) 2025-06-14 18:03:49.688993 :A4AB3493: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-14 18:03:49.688993 :A4AB3494:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=2899972875 2025-06-14 18:03:49.688993 :A4AB3495:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:03:49.688994 :A4AB3496:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:03:49.688994 :A4AB3497:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:03:49.688994 :A4AB3498:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:03:49.688995 :A4AB3499:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:03:49.689484 :A4AB349A: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-14 18:04:19.765963 :A4AB3EBE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 18:04:49.772583 :A4AB48B4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 18:04:49.761 2025-06-14 18:04:49.772586 :A4AB48B5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15476 pso_num=25 pso_serial#=100 2025-06-14 18:04:49.772733 :A4AB48B6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15476 osp_idx=23 osp_ver=157547 osp_pg=0 (spawn #285277) 2025-06-14 18:04:49.772734 :A4AB48B7: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-14 18:04:49.772734 :A4AB48B8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=2900032954 2025-06-14 18:04:49.772735 :A4AB48B9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:04:49.772735 :A4AB48BA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:04:49.772736 :A4AB48BB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:04:49.772736 :A4AB48BC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:04:49.772736 :A4AB48BD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:04:49.773231 :A4AB48BE: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-14 18:05:19.833151 :A4AB526C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-14 18:05:49.892236 :A4AB5CA8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-14 18:05:49.889 2025-06-14 18:05:49.892239 :A4AB5CA9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16160 pso_num=25 pso_serial#=101 2025-06-14 18:05:49.892358 :A4AB5CAA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16160 osp_idx=23 osp_ver=157548 osp_pg=0 (spawn #285279) 2025-06-14 18:05:49.892359 :A4AB5CAB: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-14 18:05:49.892359 :A4AB5CAC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=2900093063 2025-06-14 18:05:49.892360 :A4AB5CAD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:05:49.892360 :A4AB5CAE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:05:49.892360 :A4AB5CAF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:05:49.892361 :A4AB5CB0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:05:49.892361 :A4AB5CB1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:05:49.892834 :A4AB5CB2: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-14 18:05:54.225070 :A4AB6120: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-14 18:05:54.331784 :A4AB6458: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-14 18:05:54.331784*:A4AB6467:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1749895553 eid=16782331 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-14 18:05:54.331784*:A4AB6468:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-06-14 18:05:54.331784*:A4AB6469:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-06-14 18:05:54.331784*:A4AB646A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-06-14 18:05:54.331784*:A4AB646B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-06-14 18:05:54.331784*:A4AB646C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-06-14 18:05:54.336959 :A4AB6477: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-14 18:05:54.337002 :A4AB647A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-14 18:05:54.341028 :A4AB648A: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-14 18:05:54.448149 :A4AB6492: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-14 18:05:54.576154 :A4AB649A: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-14 18:05:54.702226 :A4AB64A2: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-14 18:05:55.825235 :A4AB6504: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-14 18:05:55.826372 :A4AB650C: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-14 18:05:55.828131 :A4AB6514: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-14 18:05:55.830746 :A4AB651C: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-14 18:05:55.832302 :A4AB6524: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-14 18:05:55.833791 :A4AB652C: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-14 18:05:55.850169 :A4AB6534: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-14 18:05:55.850945 :A4AB6548: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-14 18:05:55.851536 :A4AB6550: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-14 18:05:55.852053 :A4AB6558: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-14 18:05:55.854755 :A4AB6560: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-14 18:05:55.855691 :A4AB6568:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.856462 :A4AB6569:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.857247 :A4AB656A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.857670 :A4AB656B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.857676 :A4AB656C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.858255 :A4AB656D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.858756 :A4AB656E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.859133 :A4AB656F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.860229 :A4AB6570:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.898507 :A4AB6571: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-14 18:05:55.898566 :A4AB6574:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.899512 :A4AB657A: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-14 18:05:55.900494 :A4AB6582:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.900981 :A4AB6583: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-14 18:05:55.918856 :A4AB658B: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-14 18:05:55.918909 :A4AB658E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.919911 :A4AB6594: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-14 18:05:55.920833 :A4AB659C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.921326 :A4AB659D: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-14 18:05:55.942149 :A4AB65A8: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-14 18:05:55.942196 :A4AB65AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.943047 :A4AB65B1: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-14 18:05:55.943961 :A4AB65B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.944393 :A4AB65BA: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-14 18:05:55.965684 :A4AB65D9: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-14 18:05:55.965739 :A4AB65DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.965739*:A4AB65E2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DAEFCB8 estart=1749895555 eid=16778106 ctx=0x0000000773524750 cwh=0x0000000026C3C718 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-14 18:05:55.965739*:A4AB65E3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DAEFCB8 curCtx=0x000000002DB449F8 pubTabIdxCnt=0 2025-06-14 18:05:55.965739*:A4AB65E4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DAEFCB8 2025-06-14 18:05:55.965739*:A4AB65E5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DAEFCB8 2025-06-14 18:05:55.965739*:A4AB65E6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DAEFCB8 curCtx=0x000000002DB449F8 xsc->flg4=65792 flags=1 2025-06-14 18:05:55.965739*:A4AB65E7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DAEFCB8 2025-06-14 18:05:55.966633 :A4AB65E8: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-14 18:05:55.967484 :A4AB65F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-14 18:05:55.967916 :A4AB65F1: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-14 18:05:55.989911 :A4AB65F9: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-14 18:05:55.989984 :A4AB65FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-14 18:05:55.990988 :A4AB660C: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-14 18:05:55.991627 :A4AB6614: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-14 18:05:55.992006 :A4AB661C: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-14 18:05:55.992192 :A4AB6624: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-14 18:05:55.992326 :A4AB662C: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-14 18:05:55.992513 :A4AB6634:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-14 18:05:55.996818 :A4AB6635: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-14 18:05:55.996898 :A4AB6638: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-14 18:05:55.996983 :A4AB6645: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: 16160, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de