Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250427060009\orcl_j001_7880_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250427060009\orcl_j001_7880_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:37599M/63366M, Ph+PgF:45282M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 7880, image: ORACLE.EXE (J001) *** 2025-04-27 06:00:09.722 *** SESSION ID:(201.17057) 2025-04-27 06:00:09.722 *** 2025-04-27 06:00:09.722 Process diagnostic dump for ORACLE.EXE (J001), OS id=7880, pid: 25, proc_ser: 80, sid: 201, sess_ser: 17057 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 7880 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.054735 sec, exc=0.054735 sec, total=0.054735 sec wait times: max=0.500000 sec, heur=7.661663 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.000014 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499976 sec, exc=0.499976 sec, total=0.499976 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510116 sec, exc=0.510116 sec, total=0.510116 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000011 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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 Sampled Session History of session 201 serial 17057 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 06:00:02 - 06:00:09] 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-04-27 06:00:09.722 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 7880, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-27 05:47:38.102371 :8F54F2E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3008 pso_num=25 pso_serial#=68 2025-04-27 05:47:38.102514 :8F54F2EA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3008 osp_idx=23 osp_ver=66112 osp_pg=0 (spawn #122710) 2025-04-27 05:47:38.102515 :8F54F2EB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:47:38.102516 :8F54F2EC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=3003659890 2025-04-27 05:47:38.102516 :8F54F2ED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:47:38.102516 :8F54F2EE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:47:38.102517 :8F54F2EF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:47:38.102517 :8F54F2F0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:47:38.102517 :8F54F2F1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:47:38.102994 :8F54F2F2: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-04-27 05:48:08.172402 :8F54FC9A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:48:38.195458 :8F5506F9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:48:38.184 2025-04-27 05:48:38.195460 :8F5506FA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10760 pso_num=25 pso_serial#=69 2025-04-27 05:48:38.195589 :8F5506FB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10760 osp_idx=23 osp_ver=66113 osp_pg=0 (spawn #122713) 2025-04-27 05:48:38.195590 :8F5506FC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:48:38.195590 :8F5506FD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=3003719984 2025-04-27 05:48:38.195591 :8F5506FE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:48:38.195591 :8F5506FF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:48:38.195591 :8F550700:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:48:38.195592 :8F550701:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:48:38.195592 :8F550702:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:48:38.196058 :8F550703: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-04-27 05:49:08.286791 :8F5510E7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:49:38.289603 :8F551ACF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:49:38.278 2025-04-27 05:49:38.289605 :8F551AD0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=5008 pso_num=25 pso_serial#=70 2025-04-27 05:49:38.289732 :8F551AD1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=5008 osp_idx=23 osp_ver=66114 osp_pg=0 (spawn #122715) 2025-04-27 05:49:38.289733 :8F551AD2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:49:38.289733 :8F551AD3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=3003780078 2025-04-27 05:49:38.289734 :8F551AD4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:49:38.289734 :8F551AD5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:49:38.289735 :8F551AD6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:49:38.289735 :8F551AD7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:49:38.289735 :8F551AD8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:49:38.290266 :8F551AD9: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-04-27 05:50:08.373926 :8F552516:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:50:33.344673 :8F552D6E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:50:33.334 2025-04-27 05:50:33.344675 :8F552D6F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8620 pso_num=25 pso_serial#=71 2025-04-27 05:50:33.344808 :8F552D70:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8620 osp_idx=23 osp_ver=66115 osp_pg=0 (spawn #122717) 2025-04-27 05:50:33.344808 :8F552D71:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:50:33.344809 :8F552D72:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=3003835140 2025-04-27 05:50:33.344809 :8F552D73:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:50:33.344810 :8F552D74:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:50:33.344810 :8F552D75:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:50:33.344810 :8F552D76:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:50:33.344810 :8F552D77:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:50:33.345273 :8F552D78: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-04-27 05:50:38.340782 :8F552F0E: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-04-27 05:50:38.340810*:8F552F0F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DCBB58 estart=1745704238 eid=16832381 ctx=0x000000077BD98A08 cwh=0x0000000024D801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-27 05:50:38.340810*:8F552F10:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 pubTabIdxCnt=0 2025-04-27 05:50:38.340810*:8F552F11:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DCBB58 2025-04-27 05:50:38.340810*:8F552F12:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DCBB58 2025-04-27 05:50:38.340810*:8F552F13:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 xsc->flg4=65792 flags=2 2025-04-27 05:50:38.340810*:8F552F14:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DCBB58 2025-04-27 05:50:38.341360 :8F552F15: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-04-27 05:50:38.352414 :8F552F1D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-27 05:50:38.352932 :8F552F1E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-27 05:50:38.355233 :8F552F1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 05:50:38.355580 :8F552F20: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-04-27 05:50:38.355619 :8F552F23:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 05:50:38.356480 :8F552F29: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-04-27 05:50:38.356601 :8F552F32: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-04-27 05:51:08.443517 :8F553904:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:51:38.447923 :8F554315:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:51:38.437 2025-04-27 05:51:38.447925 :8F554316:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10552 pso_num=25 pso_serial#=72 2025-04-27 05:51:38.448040 :8F554317:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10552 osp_idx=23 osp_ver=66116 osp_pg=0 (spawn #122719) 2025-04-27 05:51:38.448041 :8F554318:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:51:38.448041 :8F554319:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=3003900234 2025-04-27 05:51:38.448042 :8F55431A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:51:38.448042 :8F55431B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:51:38.448042 :8F55431C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:51:38.448043 :8F55431D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:51:38.448043 :8F55431E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:51:38.448539 :8F55431F: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-04-27 05:52:08.529505 :8F554D9F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:52:38.545966 :8F5557A2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:52:38.535 2025-04-27 05:52:38.545968 :8F5557A3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6232 pso_num=25 pso_serial#=73 2025-04-27 05:52:38.546096 :8F5557A4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6232 osp_idx=23 osp_ver=66117 osp_pg=0 (spawn #122721) 2025-04-27 05:52:38.546097 :8F5557A5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:52:38.546097 :8F5557A6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=3003960328 2025-04-27 05:52:38.546098 :8F5557A7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:52:38.546098 :8F5557A8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:52:38.546098 :8F5557A9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:52:38.546098 :8F5557AA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:52:38.546099 :8F5557AB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:52:38.546571 :8F5557AC: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-04-27 05:53:08.631686 :8F5561A9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:53:38.636552 :8F556BC4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:53:38.626 2025-04-27 05:53:38.636554 :8F556BC5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10412 pso_num=25 pso_serial#=74 2025-04-27 05:53:38.636680 :8F556BC6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10412 osp_idx=23 osp_ver=66118 osp_pg=0 (spawn #122724) 2025-04-27 05:53:38.636681 :8F556BC7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:53:38.636681 :8F556BC8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=128 time=3004020421 2025-04-27 05:53:38.636682 :8F556BC9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:53:38.636682 :8F556BCA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:53:38.636683 :8F556BCB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:53:38.636683 :8F556BCC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:53:38.636684 :8F556BCD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:53:38.637244 :8F556BCE: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-04-27 05:54:08.723921 :8F5575D1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:54:38.715831 :8F557FEC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:54:38.704 2025-04-27 05:54:38.715832 :8F557FED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6556 pso_num=25 pso_serial#=75 2025-04-27 05:54:38.715946 :8F557FEE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6556 osp_idx=23 osp_ver=66119 osp_pg=0 (spawn #122726) 2025-04-27 05:54:38.715947 :8F557FEF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:54:38.715948 :8F557FF0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=129 time=3004080500 2025-04-27 05:54:38.715948 :8F557FF1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:54:38.715948 :8F557FF2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:54:38.715949 :8F557FF3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:54:38.715949 :8F557FF4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:54:38.715949 :8F557FF5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:54:38.716449 :8F557FF6: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-04-27 05:55:08.771280 :8F558A24:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:55:38.790963 :8F559434:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:55:38.779 2025-04-27 05:55:38.790965 :8F559435:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7712 pso_num=25 pso_serial#=76 2025-04-27 05:55:38.791097 :8F559436:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7712 osp_idx=23 osp_ver=66120 osp_pg=0 (spawn #122728) 2025-04-27 05:55:38.791098 :8F559437:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:55:38.791099 :8F559438:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=3004140578 2025-04-27 05:55:38.791099 :8F559439:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:55:38.791099 :8F55943A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:55:38.791100 :8F55943B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:55:38.791100 :8F55943C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:55:38.791101 :8F55943D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:55:38.791609 :8F55943E: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-04-27 05:56:08.849351 :8F559E1B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:56:38.875577 :8F55A81B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:56:38.864 2025-04-27 05:56:38.875579 :8F55A81C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10852 pso_num=25 pso_serial#=77 2025-04-27 05:56:38.875714 :8F55A81D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10852 osp_idx=23 osp_ver=66121 osp_pg=0 (spawn #122730) 2025-04-27 05:56:38.875714 :8F55A81E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:56:38.875715 :8F55A81F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=3004200671 2025-04-27 05:56:38.875715 :8F55A820:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:56:38.875716 :8F55A821:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:56:38.875716 :8F55A822:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:56:38.875718 :8F55A823:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:56:38.875718 :8F55A824:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:56:38.876199 :8F55A825: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-04-27 05:57:08.970520 :8F55B33E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:57:38.935416 :8F55BD21:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:57:38.924 2025-04-27 05:57:38.935418 :8F55BD22:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7976 pso_num=25 pso_serial#=78 2025-04-27 05:57:38.935548 :8F55BD23:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7976 osp_idx=23 osp_ver=66122 osp_pg=0 (spawn #122732) 2025-04-27 05:57:38.935548 :8F55BD24:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:57:38.935549 :8F55BD25:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=132 time=3004260718 2025-04-27 05:57:38.935549 :8F55BD26:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:57:38.935550 :8F55BD27:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:57:38.935550 :8F55BD28:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:57:38.935550 :8F55BD29:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:57:38.935551 :8F55BD2A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:57:38.936041 :8F55BD2B: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-04-27 05:58:09.031665 :8F55C72B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:58:39.023012 :8F55D144:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:58:39.011 2025-04-27 05:58:39.023014 :8F55D145:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10344 pso_num=25 pso_serial#=79 2025-04-27 05:58:39.023148 :8F55D146:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10344 osp_idx=23 osp_ver=66123 osp_pg=0 (spawn #122735) 2025-04-27 05:58:39.023148 :8F55D147:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:58:39.023149 :8F55D148:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=133 time=3004320812 2025-04-27 05:58:39.023149 :8F55D149:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:58:39.023150 :8F55D14A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:58:39.023150 :8F55D14B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:58:39.023150 :8F55D14C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:58:39.023150 :8F55D14D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:58:39.023630 :8F55D14E: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-04-27 05:59:09.092785 :8F55DB15:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 05:59:39.125366 :8F55E4F0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 05:59:39.114 2025-04-27 05:59:39.125368 :8F55E4F1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7880 pso_num=25 pso_serial#=80 2025-04-27 05:59:39.125499 :8F55E4F2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7880 osp_idx=23 osp_ver=66124 osp_pg=0 (spawn #122737) 2025-04-27 05:59:39.125499 :8F55E4F3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 05:59:39.125500 :8F55E4F4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=134 time=3004380921 2025-04-27 05:59:39.125500 :8F55E4F5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 05:59:39.125500 :8F55E4F6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 05:59:39.125500 :8F55E4F7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 05:59:39.125501 :8F55E4F8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 05:59:39.125501 :8F55E4F9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 05:59:39.125993 :8F55E4FA: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-04-27 06:00:02.035685 :8F55EEB6: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-04-27 06:00:02.035685*:8F55EEC5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDC718 estart=1745704801 eid=16779345 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-27 06:00:02.035685*:8F55EEC6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 pubTabIdxCnt=0 2025-04-27 06:00:02.035685*:8F55EEC7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDC718 2025-04-27 06:00:02.035685*:8F55EEC8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDC718 2025-04-27 06:00:02.035685*:8F55EEC9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 xsc->flg4=65792 flags=2 2025-04-27 06:00:02.035685*:8F55EECA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDC718 2025-04-27 06:00:02.041226 :8F55EEDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-27 06:00:02.041257 :8F55EEE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-04-27 06:00:02.052532 :8F55EF3F: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-04-27 06:00:02.057405 :8F55EF5C: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-04-27 06:00:02.058115 :8F55EF77: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-04-27 06:00:02.063812 :8F55EF91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-27 06:00:02.063970 :8F55EFA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 06:00:02.064876 :8F55EFA9: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-04-27 06:00:02.065119 :8F55EFB1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-04-27 06:00:02.066711 :8F55EFB3: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-04-27 06:00:02.066756 :8F55EFB6: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-04-27 06:00:02.066820 :8F55EFC3: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-04-27 06:00:02.145109 :8F55F21A: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: 7880, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de