Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816060006\orcl_j001_18088_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816060006\orcl_j001_18088_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:35457M/63366M, Ph+PgF:40781M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 18088, image: ORACLE.EXE (J001) *** 2025-08-16 06:00:06.162 *** SESSION ID:(201.11873) 2025-08-16 06:00:06.162 *** 2025-08-16 06:00:06.162 Process diagnostic dump for ORACLE.EXE (J001), OS id=18088, pid: 25, proc_ser: 104, sid: 201, sess_ser: 11873 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18088 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.490412 sec, exc=0.490412 sec, total=0.490412 sec wait times: max=0.500000 sec, heur=4.105616 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=6 seq_num=7 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 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=4 seq_num=5 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.508505 sec, exc=0.508505 sec, total=0.508505 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.509303 sec, exc=0.509303 sec, total=0.509303 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.097338 sec, exc=0.097338 sec, total=0.097338 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 11873 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 06:00:02 - 06:00:06] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 06:00:06.162 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 18088, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 05:47:46.888044 :C05D9402:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=191 time=4003936189 2025-08-16 05:47:46.888044 :C05D9403:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:47:46.888045 :C05D9404:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:47:46.888045 :C05D9405:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:47:46.888045 :C05D9406:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:47:46.888046 :C05D9407:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:47:46.888539 :C05D9408: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-16 05:48:17.008918 :C05D9DC3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:48:47.009334 :C05DA75F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:48:46.998 2025-08-16 05:48:47.009335 :C05DA760:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19316 pso_num=25 pso_serial#=93 2025-08-16 05:48:47.009460 :C05DA761:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19316 osp_idx=23 osp_ver=243220 osp_pg=0 (spawn #493561) 2025-08-16 05:48:47.009461 :C05DA762: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-16 05:48:47.009461 :C05DA763:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=192 time=4003996314 2025-08-16 05:48:47.009462 :C05DA764:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:48:47.009462 :C05DA765:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:48:47.009462 :C05DA766:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:48:47.009463 :C05DA767:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:48:47.009463 :C05DA768:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:48:47.009949 :C05DA769: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-16 05:49:17.083428 :C05DB132:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:49:47.072847 :C05DBADC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:49:47.061 2025-08-16 05:49:47.072849 :C05DBADD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14636 pso_num=25 pso_serial#=94 2025-08-16 05:49:47.072978 :C05DBADE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14636 osp_idx=23 osp_ver=243221 osp_pg=0 (spawn #493563) 2025-08-16 05:49:47.072979 :C05DBADF: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-16 05:49:47.072979 :C05DBAE0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=193 time=4004056376 2025-08-16 05:49:47.072980 :C05DBAE1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:49:47.072980 :C05DBAE2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:49:47.072981 :C05DBAE3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:49:47.072981 :C05DBAE4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:49:47.072981 :C05DBAE5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:49:47.073479 :C05DBAE6: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-16 05:50:17.138293 :C05DC480:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:50:47.143693 :C05DCF0B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:50:47.133 2025-08-16 05:50:47.143696 :C05DCF0C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19460 pso_num=25 pso_serial#=95 2025-08-16 05:50:47.143871 :C05DCF0D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19460 osp_idx=23 osp_ver=243222 osp_pg=0 (spawn #493565) 2025-08-16 05:50:47.143872 :C05DCF0E: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-16 05:50:47.143873 :C05DCF0F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=194 time=4004116439 2025-08-16 05:50:47.143873 :C05DCF10:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:50:47.143873 :C05DCF11:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:50:47.143873 :C05DCF12:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:50:47.143874 :C05DCF13:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:50:47.143874 :C05DCF14:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:50:47.144372 :C05DCF15: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-16 05:51:17.230797 :C05DD9B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:51:47.237844 :C05DE380:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:51:47.226 2025-08-16 05:51:47.237846 :C05DE381:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21060 pso_num=25 pso_serial#=96 2025-08-16 05:51:47.237995 :C05DE382:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21060 osp_idx=23 osp_ver=243223 osp_pg=0 (spawn #493568) 2025-08-16 05:51:47.237996 :C05DE383: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-16 05:51:47.237996 :C05DE384:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=195 time=4004176533 2025-08-16 05:51:47.237997 :C05DE385:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:51:47.237997 :C05DE386:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:51:47.237998 :C05DE387:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:51:47.237998 :C05DE388:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:51:47.237998 :C05DE389:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:51:47.238490 :C05DE38A: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-16 05:52:02.246093 :C05DE891: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-16 05:52:32.337767 :C05DF260:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:52:47.310808 :C05DF759:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:52:47.299 2025-08-16 05:52:47.310810 :C05DF75A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22424 pso_num=25 pso_serial#=97 2025-08-16 05:52:47.310924 :C05DF75B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22424 osp_idx=23 osp_ver=243224 osp_pg=0 (spawn #493570) 2025-08-16 05:52:47.310925 :C05DF75C: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-16 05:52:47.310925 :C05DF75D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=196 time=4004236611 2025-08-16 05:52:47.310926 :C05DF75E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:52:47.310926 :C05DF75F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:52:47.310927 :C05DF760:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:52:47.310928 :C05DF761:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:52:47.310928 :C05DF762:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:52:47.311432 :C05DF763: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-16 05:53:17.411509 :C05E0138:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:53:47.406047 :C05E0AD9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:53:47.395 2025-08-16 05:53:47.406049 :C05E0ADA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1876 pso_num=25 pso_serial#=98 2025-08-16 05:53:47.406166 :C05E0ADB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1876 osp_idx=23 osp_ver=243225 osp_pg=0 (spawn #493572) 2025-08-16 05:53:47.406167 :C05E0ADC: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-16 05:53:47.406167 :C05E0ADD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=197 time=4004296704 2025-08-16 05:53:47.406168 :C05E0ADE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:53:47.406168 :C05E0ADF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:53:47.406169 :C05E0AE0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:53:47.406169 :C05E0AE1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:53:47.406169 :C05E0AE2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:53:47.406645 :C05E0AE3: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-16 05:54:17.453523 :C05E149E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:54:47.501442 :C05E1E56:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:54:47.490 2025-08-16 05:54:47.501444 :C05E1E57:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21124 pso_num=25 pso_serial#=99 2025-08-16 05:54:47.501559 :C05E1E58:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21124 osp_idx=23 osp_ver=243226 osp_pg=0 (spawn #493574) 2025-08-16 05:54:47.501560 :C05E1E59: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-16 05:54:47.501561 :C05E1E5A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=198 time=4004356798 2025-08-16 05:54:47.501561 :C05E1E5B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:54:47.501561 :C05E1E5C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:54:47.501562 :C05E1E5D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:54:47.501562 :C05E1E5E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:54:47.501563 :C05E1E5F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:54:47.502053 :C05E1E60: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-16 05:55:17.584527 :C05E27FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:55:47.589325 :C05E31B4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:55:47.577 2025-08-16 05:55:47.589327 :C05E31B5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20700 pso_num=25 pso_serial#=100 2025-08-16 05:55:47.589452 :C05E31B6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20700 osp_idx=23 osp_ver=243227 osp_pg=0 (spawn #493576) 2025-08-16 05:55:47.589452 :C05E31B7: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-16 05:55:47.589453 :C05E31B8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=199 time=4004416892 2025-08-16 05:55:47.589454 :C05E31B9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:55:47.589454 :C05E31BA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:55:47.589454 :C05E31BB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:55:47.589454 :C05E31BC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:55:47.589455 :C05E31BD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:55:47.589946 :C05E31BE: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-16 05:56:17.672300 :C05E3BFB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:56:47.651525 :C05E463E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:56:47.639 2025-08-16 05:56:47.651527 :C05E463F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20804 pso_num=25 pso_serial#=101 2025-08-16 05:56:47.651648 :C05E4640:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20804 osp_idx=23 osp_ver=243228 osp_pg=0 (spawn #493580) 2025-08-16 05:56:47.651648 :C05E4641: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-16 05:56:47.651649 :C05E4642:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=200 time=4004476954 2025-08-16 05:56:47.651649 :C05E4643:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:56:47.651650 :C05E4644:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:56:47.651650 :C05E4645:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:56:47.651650 :C05E4646:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:56:47.651651 :C05E4647:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:56:47.652155 :C05E4648: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-16 05:57:17.713626 :C05E4FD3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:57:47.736559 :C05E596F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:57:47.725 2025-08-16 05:57:47.736561 :C05E5970:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20168 pso_num=25 pso_serial#=102 2025-08-16 05:57:47.736683 :C05E5971:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20168 osp_idx=23 osp_ver=243229 osp_pg=0 (spawn #493582) 2025-08-16 05:57:47.736684 :C05E5972: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-16 05:57:47.736684 :C05E5973:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=201 time=4004537033 2025-08-16 05:57:47.736684 :C05E5974:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:57:47.736685 :C05E5975:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:57:47.736685 :C05E5976:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:57:47.736685 :C05E5977:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:57:47.736685 :C05E5978:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:57:47.737149 :C05E5979: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-16 05:58:17.786948 :C05E6320:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:58:47.829058 :C05E6CD6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:58:47.817 2025-08-16 05:58:47.829060 :C05E6CD7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21544 pso_num=25 pso_serial#=103 2025-08-16 05:58:47.829182 :C05E6CD8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21544 osp_idx=23 osp_ver=243230 osp_pg=0 (spawn #493584) 2025-08-16 05:58:47.829182 :C05E6CD9: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-16 05:58:47.829183 :C05E6CDA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=202 time=4004597126 2025-08-16 05:58:47.829183 :C05E6CDB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:58:47.829183 :C05E6CDC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:58:47.829184 :C05E6CDD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:58:47.829184 :C05E6CDE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:58:47.829184 :C05E6CDF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:58:47.829659 :C05E6CE0: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-16 05:59:17.931001 :C05E771B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-16 05:59:47.958086 :C05E80C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-16 05:59:47.947 2025-08-16 05:59:47.958088 :C05E80C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18088 pso_num=25 pso_serial#=104 2025-08-16 05:59:47.958208 :C05E80C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18088 osp_idx=23 osp_ver=243231 osp_pg=0 (spawn #493586) 2025-08-16 05:59:47.958209 :C05E80C5: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-16 05:59:47.958209 :C05E80C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=203 time=4004657251 2025-08-16 05:59:47.958210 :C05E80C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:59:47.958210 :C05E80C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:59:47.958211 :C05E80C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:59:47.958211 :C05E80CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:59:47.958211 :C05E80CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:59:47.958697 :C05E80CC: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-16 06:00:02.034559 :C05E86F2: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-16 06:00:02.034559*:C05E8701:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB898 estart=1755295201 eid=16786166 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-16 06:00:02.034559*:C05E8702:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 pubTabIdxCnt=0 2025-08-16 06:00:02.034559*:C05E8703:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB898 2025-08-16 06:00:02.034559*:C05E8704:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB898 2025-08-16 06:00:02.034559*:C05E8705:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB898 curCtx=0x0000000026BE7AB8 xsc->flg4=65792 flags=2 2025-08-16 06:00:02.034559*:C05E8706:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB898 2025-08-16 06:00:02.040130 :C05E8711: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-16 06:00:02.040171 :C05E8714:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-16 06:00:02.045155 :C05E873E:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-16 06:00:02.045765 :C05E8752:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 0f84120000000000 2025-08-16 06:00:02.047504 :C05E8768: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-08-16 06:00:02.047655 :C05E8775: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-08-16 06:00:02.047665 :C05E877C:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-08-16 06:00:02.047673 :C05E877D: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-08-16 06:00:02.047703 :C05E8789: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-08-16 06:00:02.048464 :C05E8795:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 234583381) 2025-08-16 06:00:02.048468 :C05E8796: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-08-16 06:00:02.048493 :C05E87A1: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-08-16 06:00:02.048501 :C05E87AE:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65561 1) 2025-08-16 06:00:02.048504 :C05E87AF: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-08-16 06:00:02.048529 :C05E87BB: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-08-16 06:00:02.048532 :C05E87C5:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65561) 2025-08-16 06:00:02.048534 :C05E87C8: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-08-16 06:00:02.048585 :C05E87E8: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-08-16 06:00:02.049455 :C05E887C: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-08-16 06:00:02.049475 :C05E887D:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-16 06:00:02.050272 :C05E888C:db_trace:kcc.c@13684:kccwbp(): [10021:25:201:1] 0115000000000000 0115000000000000 1084120000000000 2025-08-16 06:00:02.050641 :C05E888D:db_trace:ksb.c@6610:ksbcic_int(): [10254:25:201] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 234583381) 2025-08-16 06:00:02.050648 :C05E888E: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-08-16 06:00:02.050672 :C05E8899: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-08-16 06:00:02.059427 :C05E8903:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-16 06:00:02.061272 :C05E8914: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-16 06:00:02.061319 :C05E8917: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-16 06:00:02.061365 :C05E8924: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-16 06:00:02.158975 :C05E8A71: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 18088, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de