10046 trace

收集10046 trace

Event 10046是为Oracle session收集扩展的sql_trace信息的标准方法。
关于这个event的详细描述请参见以下文档：

Note 21154.1  EVENT: 10046 "enable SQL statement tracing (including binds/waits)"

通常为了诊断SQL调优类问题，我们需要记录下这些语句在执行过程中产生的等待以及bind variables(绑定变量)的信息。 这些信息可以通过级别为12的10046 trace获得。下面的例子列举了在各种场景下，如何设定10046事件。

• Trace文件的位置
• 在Session级打开trace
• 跟踪一个已经开始的进程
• 实例层的跟踪
• 初始化参数设置
• 通过logon trigger设置跟踪
• 用SQLT收集trace
• 用DBMS_MONITOR进行跟踪
• 其它特定场景下打开跟踪的方法
• Trace文件解析

• Trace文件的位置
  
  11g R1以上:
  
  从11gR1开始，Oracle引入了新的诊断结构，以参数DIAGNOSTIC_DEST控制存放trace文件与core文件的路径。
  可以用以下命令，获取DIAGNOSTIC_DEST的位置：
  
    SQL> show parameter diagnostic_dest
  
  11gR1以前:
  
  如果是用户进程，10046 trace文件会被生成在user_dump_dest下；如果是后台进程，trace文件会被生成在background_dump_dest下。
  下面的命令可以显示user_dump_dest:
    SQL> show parameter user_dump_dest
  
  
  注：下面的某些例子中会设定tracefile_identifier，通过这个设置可以帮助我们更容易的找到生成的trace文件。
  
  
• 在Session级打开trace
  
  适用于SQL语句可以在新的session创建后再运行。
  在session级收集10046 trace：
  
  alter session set tracefile_identifier='10046'; 
  
  alter session set timed_statistics = true;
  alter session set statistics_level=all;
  alter session set max_dump_file_size = unlimited;
  
  alter session set events '10046 trace name context forever,level 12';
  
  -- 执行需要被trace的SQL --
  
  select * from dual;
  exit;
  如果不退出当前session, 可以用以下命令关闭trace:
  
  alter session set events '10046 trace name context off';
  注意，如果session没有被彻底地关闭并且跟踪被停止了，某些重要的trace信息的可能会丢失。
  
  注意：这里我们将"statistics_level"设置为all，这是因为有可能这个参数在系统级不是默认值"TYPICAL"（比如 BASIC）。为了收集性能相关问题的信息我们需要打开某个级别的statistics。我们推荐在 session 级将这个参数设置成 ALL 以便于收集更多的信息，尽管这不是必须的。
   
  
• 跟踪一个已经开始的进程
  
  如果需要跟踪一个已经存在session，可以用 oradebug连接到session上，并发起10046 trace。
  
  
  1. 首先，用某种方法找到需要被跟踪的session.
     
     例如，在SQL*Plus里，找出目标session的OS的进程ID(spid):
     
     select p.PID,p.SPID,s.SID
     from v$process p,v$session s
     where s.paddr = p.addr
     and s.sid = &SESSION_ID
     /
     SPID 是操作系统的进程标识符（os pid）
     PID 是Oracle的进程标识符(ora pid)
     
     
  
  
  如果你不知道session的ID, 那么可以使用类似下面的SQL语句来帮助你找到它
  
  
  column line format a79
  set heading off
  select 'ospid: ' || p.spid || ' # ''' ||s.sid||','||s.serial#||''' '||
    s.osuser || ' ' ||s.machine ||' '||s.username ||' '||s.program line
  from v$session s , v$process p
  where p.addr = s.paddr
  and s.username <> ' ';
   
  
  
  如果是使用了12c的multi thread下，那么需要使用v$process中新的列stid来找到对应的thread, 因为Oracle把多个processes放进了一个单独的 ospid 中。如果想找到特定的thread, 使用下面的语法:
  
  
  
  oradebug setospid <spid> <stid>
  
  
  
• 一旦找到OS PID，就可以用以下命令初始化跟踪：
  
  假设需要被跟踪的OSPID是9834。
  以sysdba的身份登录到SQL*Plus并执行下面的命令：
  
  connect / as sysdba
  oradebug setospid 9834
  oradebug unlimit
  oradebug event 10046 trace name context forever,level 12
  记得把例子中的'9834' 替换成真实的os pid。

注： 也可以通过oradebug使用 'setorapid'命令连接到一个session。

下面的例中， 使用

PID

（Oracle进程标识符）(而不是SPID), oradebug命令将被改为：

connect / as sysdba
oradebug setorapid 9834
oradebug unlimit
oradebug event 10046 trace name context forever,level 12

记得把例子中的9834替换成真实的ora pid。

跟踪过程完成以后，关闭oradebug跟踪：

oradebug event 10046 trace name context off

         如果是使用了12c的multi thread下，那么需要使用v$process中新的列stid来找到对应的thread, 因为Oracle把多个processes放进了一个单独的 ospid 中。如果想找到特定的thread, 使用下面的语法::

          oradebug setospid <spid> <stid>oradebug unlimit

         tracefile名字会是 <instance><spid>_<stid>.trc 的格式.

• Instance wide tracing 
  
  

• 实例层的跟踪
  
  
  注意: 在实例层设置跟踪需要非常小心，这是因为整体性能会由于所有session都被跟踪而受到影响。
  
  这个设置将会跟踪在这个参数设置“以后”创建的每个session。已经存在的session不会被跟踪。
  
  系统层的10046跟踪适用于当我们知道问题session会出现，但是不能预先识别它的时候。
  在这种情况下，可以打开系统层跟踪一小段时间，当问题被重现以后立即将其关闭，然后从已经生成的trace中查找需要的信息。
  
  用以下命令打开系统层的跟踪：
  
  alter system set events '10046 trace name context forever,level 12';
  用以下命令关闭在所有session中的10046跟踪：
  
  alter system set events '10046 trace name context off';

• 初始化参数设置
  
  设置以下参数并重新启动实例后，实例上所有的session都会打开跟踪。
  
  event="10046 trace name context forever,level 12"
  移除这个参数并且重启实例， 或者使用下面的alter system命令可以关闭跟踪。
  
  alter system set events '10046 trace name context off';

• 通过logon trigger设置跟踪
  
  有的时候当需要跟踪某个特定用户的操作时，可以使用logon trigger来打开跟踪
  下面是一个例子:
  
  CREATE OR REPLACE TRIGGER SYS.set_trace
  AFTER LOGON ON DATABASE
  WHEN (USER like '&USERNAME')
  DECLARE
  lcommand varchar(200);
  BEGIN
  EXECUTE IMMEDIATE 'alter session set tracefile_identifier=''From_Trigger''';
  EXECUTE IMMEDIATE 'alter session set statistics_level=ALL'; 
  EXECUTE IMMEDIATE 'alter session set max_dump_file_size=UNLIMITED';
  EXECUTE IMMEDIATE 'alter session set events ''10046 trace name context forever, level 12''';
  END set_trace;
  /
  为了能打开跟踪session, 执行trigger的用户需要被显式地授予'alter session' 权限. 例如，
  
  

  grant alter session to <USERNAME> ;

• 用SQLT收集trace
  
  SQLTXPLAIN的Xecute方法生成的诊断文件中会包含10046 trace。正如XECUTE这个名字所示，SQLT会执行被分析的SQL语句， 然后生成一个诊断文件集（包括10046 trace）。详见：
  
  
  Note:215187.1 SQLT (SQLTXPLAIN) - Tool that helps to diagnose SQL statements performing poorly (Doc ID 215187.1)
  
  用这种方法生成的trace文件会被包含在SQLT输出包中， 格式为：
  
  sqlt_s12345_10046_execute.trc
  
  12345是SQLT报告ID。
  
  

• 用DBMS_MONITOR进行跟踪
  
  DBMS_MONITOR包提供几个打开跟踪的方法。详细请见：
  
  
  Note:293661.1 Tracing Enhancements In 10g Using DBMS_MONITOR
  
  Oracle Database PL/SQL Packages and Types Reference
  10g Release 2 (10.2)
  Part Number B14258-02
  Chapter 60 DBMS_MONITOR
• 其它特定场景下打开跟踪的方法
  
  
  Note:21154.1 EVENT: 10046 "enable SQL statement tracing (including binds/waits)" 
  Note:1274511.1 General SQL_TRACE / 10046 trace Gathering Examples
  
  Note:160124.1 How to Set SQL Trace on with 10046 Event Trace which Provides the Bind Variables
  Note:371678.1 Capture 10046 Traces Upon User Login (without using a trigger)
  
  Note:1102801.1 How to Get 10046 Trace for Parallel Query
  
  
  Note:258418.1 Getting 10046 Trace for Export and Import
  
  如果您运行的是PL/SQL存储过程或包，那么可以通过使用PL/SQL profiler判断PL/SQL运行期间的时间消耗的具体信息。
  下面的文档介绍了PL/SQL profiler的使用方法：
  
  
  Note:243755.1 Implementing and Using the PL/SQL Profiler
  
  
  
  
• Trace文件解析
  
  
  Note:199081.1 SQL_TRACE (10046), TKProf and Explain Plan - Overview Reference 
  Note:39817.1 Interpreting Raw SQL_TRACE and DBMS_SUPPORT.START_TRACE output 
  
  Note:224270.1 Trace Analyzer TRCANLZR - Interpreting Raw SQL Traces with Binds and/or Waits generated by EVENT 10046 
  
  NOTE:41634.1 - TKProf Basic Overview 
  NOTE:32951.1 - TKProf Interpretation (9i and below) 
  NOTE:760786.1 - TKProf Interpretation (9i and above) 
  NOTE:214106.1 - Using TKProf to compare actual and predicted row counts 
  
  Note:199083.1 * Master Note: SQL Query Performance Overview 
  Note:398838.1 * FAQ: SQL Query Performance - Frequently Asked Questions