Oracle执行语句跟踪(1)——使用sql trace实现语句追踪
系统上的某个接口提交数据经常超时(超过3秒),而我单独在后台数据库(Oracle)执行insert,只需要17ms。提交数据的客户端没有任何的调试日志,只能通过跟踪后台语句记录实际调用过程中的数据库执行时间。从而发现问题耗时最多的阶段。
安装dbms_support包
SQL> @?\rdbms\admin\dbmssupp.sql SQL> grant execute on dbms_support to test;
找到需要跟踪的会话,一个接口可能会建立多个会话,需要根据会话建立的时间来判断真正需要跟踪的会话。
SQL> alter session set nls_date_format='YYYY-MM-DD HH24:MI:SS'; SQL> select sid,serial#,logon_time from v$session where username=&name and program=&program; 输入 name 的值: 'test' 输入 program 的值: 'test.exe'
使用start_trace_in_session进行跟踪
SQL>exec dbms_support.start_trace_in_session(1157,59729,TRUE,TRUE);
开始跟踪后,在客户端进行对应的操作。
使用stop_trace_in_session结束跟踪
SQL> exec dbms_support.stop_trace_in_session(1157,59729);
使用以下的语句获得trace文件的位置。
SELECT d.VALUE || '\' || LOWER (RTRIM (i.INSTANCE, CHR (0))) || '_ora_' || p.spid || '.trc' trace_file_name FROM (SELECT p.spid FROM v$mystat m, v$session s, v$process p WHERE m.statistic# = 1 AND s.SID = &SID AND p.addr = s.paddr) p, (SELECT t.INSTANCE FROM v$thread t, v$parameter v WHERE v.NAME = 'thread' AND (v.VALUE = 0 OR t.thread# = TO_NUMBER (v.VALUE))) i, (SELECT VALUE FROM v$parameter WHERE NAME = 'user_dump_dest') d
用文本编辑器可以直接打开trace文件
图中的这一段就是对一个语句的从解析到返回数据的整个过程,如果统计从开始请求到消息返回客户端的时间,直接将红框中的两个tim相减就可以了, 11592216806504-11592199796058=17010446 ,其单位是1/1000000秒。若要单独获取执行时间,可以EXEC的TIM减掉PARSE的TIM,差值即为执行的时间。其它字段的说明可以参照这个 文档 。
另外也可以使用 TKPROF 将trace文件转换成为执行计划解析的文本。
D:\app\oracle\diag\rdbms\wxmesdb\wxmesdb\trace>tkprof wxmesdb_ora_6520.trc trace_1.txt TKPROF: Release 12.1.0.1.0 - Development on 星期三 1月 6 08:30:11 2016 Copyright (c) 1982, 2013, Oracle and/or its affiliates. All rights reserved.
如上图所示,可以得到执行时间为17秒,与上一步中通过TIM相减的差值一致。
--EOF--
</div> 本文由用户 jopen 自行上传分享,仅供网友学习交流。所有权归原作者,若您的权利被侵害,请联系管理员。
转载本站原创文章,请注明出处,并保留原始链接、图片水印。
本站是一个以用户分享为主的开源技术平台,欢迎各类分享!