Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250731220008\orcl_j003_20184_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250731220008\orcl_j003_20184_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:35843M/63366M, Ph+PgF:41490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 20184, image: ORACLE.EXE (J003) *** 2025-07-31 22:00:08.472 *** SESSION ID:(233.30625) 2025-07-31 22:00:08.472 *** 2025-07-31 22:00:08.472 Process diagnostic dump for ORACLE.EXE (J003), OS id=20184, pid: 29, proc_ser: 213, sid: 233, sess_ser: 30625 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20184 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12840, hash value=3748225232 Current Wait Stack: Not in wait; last wait ended 0.646803 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.646829 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000372 sec, exc=0.000372 sec, total=0.000372 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2973, block#=0x0, blocks=0x0 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.031482 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2973, block#=0x0, blocks=0x0 wait_id=133 seq_num=134 snap_id=1 wait times: snap=0.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.182812 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x6800, blocks=0x800 wait_id=132 seq_num=133 snap_id=1 wait times: snap=0.000294 sec, exc=0.000294 sec, total=0.000294 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x6000, blocks=0x800 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000338 sec, exc=0.000338 sec, total=0.000338 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x5800, blocks=0x800 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000316 sec, exc=0.000316 sec, total=0.000316 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x5000, blocks=0x800 wait_id=129 seq_num=130 snap_id=1 wait times: snap=0.000309 sec, exc=0.000309 sec, total=0.000309 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x4800, blocks=0x800 wait_id=128 seq_num=129 snap_id=1 wait times: snap=0.000316 sec, exc=0.000316 sec, total=0.000316 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000037 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x4000, blocks=0x800 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.000350 sec, exc=0.000350 sec, total=0.000350 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.346355 sec of elapsed time 9: waited for 'log file sequential read' log#=0x2973, block#=0x800, blocks=0x800 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.000206 sec, exc=0.000206 sec, total=0.000206 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002592 sec of elapsed time Sampled Session History of session 233 serial 30625 --------------------------------------------------- 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, 22:00:02 - 22:00:07] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-31 22:00:08.472 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 20184, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-31 01:00:15.182754 :B938A36F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:15.191681 :B938A377:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 2, ext 0, nex 2 2025-07-31 01:00:15.191689 :B938A378:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080f69c where: kdiwh22: kdifind 2025-07-31 01:00:15.204121 :B938A379:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:15.204135 :B938A37A:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0002.002.0000c6ec 2025-07-31 01:00:15.204445 :B938A382:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 2, ext 1, nex 3 2025-07-31 01:00:15.204452 :B938A383:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00817b8d where: ktspbwh1: ktspfsrch 2025-07-31 01:00:15.219975 :B938A384:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:15.219988 :B938A385:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0002.01f.0000c6e6 2025-07-31 01:00:15.220248 :B938A38D:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 2, ext 2, nex 4 2025-07-31 01:00:15.220254 :B938A38E:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080fb32 where: kdiwh187: kdisprobe 2025-07-31 01:00:15.227519 :B938A38F:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 2, ext 3, nex 5 2025-07-31 01:00:15.227524 :B938A390:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080fa62 where: ktswh76: ktsxadd 2025-07-31 01:00:15.240535 :B938A391:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 2, ext 4, nex 6 2025-07-31 01:00:15.240545 :B938A392:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00817bc5 where: kdiwh22: kdifind 2025-07-31 01:00:15.802424 :B938A3AC:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 2, ext 5, nex 7 2025-07-31 01:00:15.802431 :B938A3AD:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00817b9c where: kdiwh22: kdifind 2025-07-31 01:00:16.417354 :B938A3D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.433598*:B938A3E0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB253F0 estart=1753894816 eid=16780325 ctx=0x000000077392D068 cwh=0x000000002DAE05F0 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-31 01:00:16.433598*:B938A3E1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB253F0 curCtx=0x000000002DAF6548 pubTabIdxCnt=0 2025-07-31 01:00:16.433598*:B938A3E2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB253F0 2025-07-31 01:00:16.433598*:B938A3E3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB253F0 2025-07-31 01:00:16.433598*:B938A3E4:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB253F0 curCtx=0x000000002DAF6548 xsc->flg4=65792 flags=1 2025-07-31 01:00:16.433598*:B938A3E5:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB253F0 2025-07-31 01:00:16.462438 :B938A3E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.462804 :B938A3EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.463088 :B938A3F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.463105 :B938A3F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.463165 :B938A405:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-31 01:00:16.466605 :B938A413:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 01:00:23.248574 :B938A6BF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 01:00:23.240 2025-07-31 01:00:23.248576 :B938A6C0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17496 pso_num=29 pso_serial#=206 2025-07-31 01:00:23.248739 :B938A6C1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17496 (legacy spawn) 2025-07-31 01:00:23.255166 :B938A6C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:23.255342 :B938A6CB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-31 01:00:23.256276 :B938A6CC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 01:00:28.720890 :B938A827:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 01:00:28.712 2025-07-31 01:00:28.720892 :B938A828:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18292 pso_num=29 pso_serial#=207 2025-07-31 01:00:28.721029 :B938A829:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18292 (legacy spawn) 2025-07-31 01:00:28.727476 :B938A82A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:28.727658 :B938A82F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-31 01:00:28.728441 :B938A834:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 08:24:40.083907 :B95BE95D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 08:24:40.081 2025-07-31 08:24:40.083909 :B95BE95E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19484 pso_num=29 pso_serial#=208 2025-07-31 08:24:40.086653 :B95BE95F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19484 osp_idx=27 osp_ver=15252 osp_pg=0 (spawn #440608) 2025-07-31 08:24:40.086655 :B95BE960:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 08:24:40.086655 :B95BE961:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=106 time=2631055954 2025-07-31 08:24:40.086656 :B95BE962:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 08:24:40.086657 :B95BE963:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 08:24:40.086657 :B95BE964:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 08:24:40.086658 :B95BE965:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 08:24:40.086658 :B95BE966:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 08:24:40.089428 :B95BE967:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-31 08:24:40.089486 :B95BE96D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-31 08:24:40.089904 :B95BE96E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 08:24:42.257144 :B95BEAC9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 08:24:42.255 2025-07-31 08:24:42.257146 :B95BEACA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19296 pso_num=29 pso_serial#=209 2025-07-31 08:24:42.257275 :B95BEACB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19296 osp_idx=27 osp_ver=15253 osp_pg=0 (spawn #440610) 2025-07-31 08:24:42.257276 :B95BEACC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 08:24:42.257277 :B95BEACD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=107 time=2631058142 2025-07-31 08:24:42.257277 :B95BEACE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 08:24:42.257277 :B95BEACF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 08:24:42.257278 :B95BEAD0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 08:24:42.257278 :B95BEAD1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 08:24:42.257278 :B95BEAD2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 08:24:42.257776 :B95BEAD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-31 08:24:42.257811 :B95BEAD9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-31 08:24:42.258181 :B95BEADA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 16:00:05.587676 :B9802CBD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 16:00:05.573 2025-07-31 16:00:05.587678 :B9802CBE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18732 pso_num=29 pso_serial#=210 2025-07-31 16:00:05.587813 :B9802CBF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18732 (legacy spawn) 2025-07-31 16:00:05.594366 :B9802CC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.594709 :B9802CC5:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-31 16:00:05.595529 :B9802CCA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 16:00:05.604513 :B9802CCD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 16:00:05.604 2025-07-31 16:00:05.604515 :B9802CCE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11796 pso_num=29 pso_serial#=211 2025-07-31 16:00:05.604664 :B9802CCF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11796 (legacy spawn) 2025-07-31 16:00:05.611374 :B9802CD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.611537 :B9802CD9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-07-31 16:00:05.612402 :B9802CDA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 16:00:05.829731 :B9802D45:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 16:00:05.823 2025-07-31 16:00:05.829733 :B9802D46:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=6024 pso_num=29 pso_serial#=212 2025-07-31 16:00:05.829865 :B9802D47:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=6024 osp_idx=27 osp_ver=15256 osp_pg=0 (spawn #441660) 2025-07-31 16:00:05.829866 :B9802D48:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 16:00:05.829866 :B9802D49:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=108 time=2658381704 2025-07-31 16:00:05.829867 :B9802D4A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 16:00:05.829867 :B9802D4B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 16:00:05.829867 :B9802D4C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 16:00:05.829868 :B9802D4D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 16:00:05.829868 :B9802D4E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 16:00:05.830351 :B9802D4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.830398 :B9802D55:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-31 16:00:05.837713 :B9802D63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.838768 :B9802D6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.839264 :B9802D73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.839772 :B9802D7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.840231 :B9802D83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.842672 :B9802D8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.843739 :B9802D93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.892162 :B9802D9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.893323 :B9802DA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.894264 :B9802DAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.894499 :B9802DB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.894745 :B9802DBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 16:00:05.896585 :B9802DC3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-31 22:00:02.057241 :B99C6F2F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-31 22:00:02.046 2025-07-31 22:00:02.057242 :B99C6F30:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20184 pso_num=29 pso_serial#=213 2025-07-31 22:00:02.057390 :B99C6F31:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20184 osp_idx=27 osp_ver=15257 osp_pg=0 (spawn #442484) 2025-07-31 22:00:02.057391 :B99C6F32:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 22:00:02.057391 :B99C6F33:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=109 time=2679977939 2025-07-31 22:00:02.057392 :B99C6F34:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 22:00:02.057392 :B99C6F35:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 22:00:02.057392 :B99C6F36:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 22:00:02.057393 :B99C6F37:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 22:00:02.057393 :B99C6F38:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 22:00:02.057999 :B99C6F39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.062904 :B99C6F50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.062934 :B99C6F53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-31 22:00:02.080497 :B99C7133:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.080782 :B99C713B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.081205 :B99C7143:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.081689 :B99C714B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.082087 :B99C7153:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.082255 :B99C715B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.082423 :B99C7163:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.082586 :B99C716B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.082740 :B99C7173:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.082894 :B99C717B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.083061 :B99C7183:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.083224 :B99C718B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.083380 :B99C7193:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.083530 :B99C719B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.083685 :B99C71A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.083848 :B99C71AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.084000 :B99C71B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.084151 :B99C71BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.084377 :B99C71C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.084539 :B99C71CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.084705 :B99C71D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.084870 :B99C71DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.085192 :B99C71E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.085354 :B99C71EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.085556 :B99C71F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.111915 :B99C71FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.467689*:B99C749C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1753970403 eid=20912531 ctx=0x0000000777968158 cwh=0x000000002B560AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-31 22:00:03.467689*:B99C749D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x000000005336C4B8 pubTabIdxCnt=0 2025-07-31 22:00:03.467689*:B99C749E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-07-31 22:00:03.467689*:B99C749F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-07-31 22:00:03.467689*:B99C74A0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003260AAF8 estart=1753970401 eid=16777489 ctx=0x000000077B54EAC8 cwh=0x000000002B560608 cpu=1187500 buffg=64490 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-31 22:00:03.467689*:B99C74A1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003260AAF8 curCtx=0x0000000053181240 pubTabIdxCnt=0 2025-07-31 22:00:03.467689*:B99C74A2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003260AAF8 2025-07-31 22:00:03.467689*:B99C74A3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003260AAF8 2025-07-31 22:00:03.467689*:B99C74A4:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x000000005336C4B8 xsc->flg4=65792 flags=1 2025-07-31 22:00:03.467689*:B99C74A5:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-07-31 22:00:03.767164 :B99C74AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=16 loc='ktm.h LINE:583 ID:ktmpsm' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.768950 :B99C74D3:db_trace:ktu.c@5576:ktugur(): [10445:29:233] des1:tsn:1 rdba:0x008127b8 where: kduwh01: kdusru 2025-07-31 22:00:03.780208 :B99C75AC:db_trace:ktu.c@5576:ktugur(): [10445:29:233] des1:tsn:1 rdba:0x008127b8 where: kduwh01: kdusru 2025-07-31 22:00:03.786012 :B99C75D9:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 8, ext 2, nex 3 2025-07-31 22:00:03.786019 :B99C75DA:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008127bf where: kduwh01: kdusru 2025-07-31 22:00:03.786019*:B99C75DB:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003260AAF8 curCtx=0x0000000053181240 xsc->flg4=65792 flags=2 2025-07-31 22:00:03.786019*:B99C75DC:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003260AAF8 2025-07-31 22:00:03.788756 :B99C75DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.809156 :B99C75FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.811122 :B99C760D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.811321 :B99C760E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.812306 :B99C760F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.812403 :B99C7612:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.813248 :B99C7624:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.814902 :B99C762E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.815197 :B99C7637:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-31 22:00:03.873945*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-31 22:00:03.955427 :B99C76C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:05.831210 :B99C78F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:05.831222 :B99C78F5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.011.0000c8bc 2025-07-31 22:00:08.282599 :B99C7AA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:08.284041 :B99C7AB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:08.294770 :B99C7AD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:08.322212 :B99C7ADB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 20184, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de