oradebug主要是给Oracle支持人员使用的,尽管很早便有,但oracle官网很少有记载。他是个sql*plus命令行工具,有sysdba的权限就可以登入,无需特别设置。他可以被用于:
1)追踪进程,或者是你的,或者是外部的
2)确定进程往哪个trc文件写
3)转储:数据文件头、内部oracle结构等
4)挂起进程,暂时的
5)确定实例使用了哪些共享内存块和信号量
6)找出RAC实例使用了哪些互联地址和协议
7)修改SGA中的数据结构
2、 oradebug使用步骤
1)启动sql*plus并以sysdba身份登入
2)连接到一个进程
3)设置一个事件或者进行诊断转储
4)检索trc文件名
5)与连接到的进程断开
3、 oradebug命令介绍
4、 连接到一个进程
在oradebug命令执行之前,你必须加入一个目标进程。这个目标进程有如下3种情况:
|
命令 |
目的 |
|
oradebug setmypid |
连接到为你的sql*plus提供服务的进程 |
|
oradebug setorapid pid |
连接到一个外部服务进程,且pid=v$process.pid |
|
oradebug setospid spid |
连接到一个外部服务进程,且spid=v$process.pid |
1)、 setmypid
sys@ORCL> oradebug setmypid
Statement processed.
sys@ORCL> oradebug unlimit
Statement processed.
sys@ORCL> oradebug event 10046 trace name context forever,level 8
Statement processed.
sys@ORCL> select * from hr.departments where department_id=10;
DEPARTMENT_ID DEPARTMENT_NAME MANAGER_ID LOCATION_ID
------------- ------------------------------ ---------- -----------
10 Administration 200 1700
sys@ORCL> oradebug tracefile_name
/u01/app/oracle/admin/orcl/udump/orcl_ora_5234.trc
sys@ORCL> oradebug event 10046 trace name context off
Statement processed.
2)、setospid(spid操作)
sys@ORCL> host top
top - 15:14:20 up 47 min, 2 users, load average: 0.22, 0.20, 0.26
Tasks: 171 total, 2 running, 168 sleeping, 0 stopped, 1 zombie
Cpu(s): 1.3%us, 1.3%sy, 0.0%ni, 97.4%id, 0.0%wa, 0.0%hi, 0.0%si, 0.0%st
Mem: 678912k total, 671192k used, 7720k free, 16672k buffers
Swap: 0k total, 0k used, 0k free, 401584k cached
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
5874 oracle 15 0 2444 1084 828 R 1.6 0.2 0:00.47 top
4781 root 15 0 53900 11m 6076 S 0.7 1.7 1:00.13 Xorg
5168 oracle 16 0 491m 17m 15m S 0.3 2.6 0:02.41 oracle
sys@ORCL> oradebug setospid 5168
Oracle pid: 7, Unix process pid: 5168, image: oracle@localhost.localdomain (CKPT)
3)、setorapid(pid操作)
sys@ORCL> select sid from v$mystat where rownum=1;
SID
----------
159
sys@ORCL> select pid,spid from v$process p,v$session s where s.sid=159 and s.paddr=p.addr;
PID SPID
---------- ------------
15 5234
sys@ORCL> oradebug setorapid 15
Statement processed.
sys@ORCL> oradebug tracefile_name
/u01/app/oracle/admin/orcl/udump/orcl_ora_5234.trc
sys@ORCL> oradebug event 10046 trace name context off
Statement processed.
注意,在OEM的top session也可以等价实现上述效果,即:确定哪些数据库会话消耗了较多的资源
5、 oradebug IPC
查看共享信息的内存的命令是ipcs [-m|-s|-q]。默认会列出共享内存、信号量,队列信息,-m列出共享内存,-s列出共享信号量,-q列出共享队列
sys@ORCL> host ipcs -m | grep ora
0xbe3bb918 5439503 oracle 640 419438592 20
sys@ORCL> oradebug setmypid
Statement processed.
sys@ORCL> oradebug ipc
Information written to trace file.
sys@ORCL> oradebug tracefile_name
/u01/app/oracle/admin/orcl/udump/orcl_ora_5234.trc
sys@ORCL> oradebug event 10046 trace name context off
Statement processed.
当一台电脑的资源有限时,失败的实例占用共享内存和信号量会导致资源紧张,从而使得新的oracle实例无法启动。
清除命令是ipcrm [-m|-s|-q] id。
-m 删除共享内存,-s删除共享信号量,-q删除共享队列
如:ipcrm -m 5439503
6、 oradebug SHORT_STACK
如果一个程序挂起,那么程序调用栈就可以显示它在调用路径中的哪一步挂起
sys@ORCL> oradebug setospid 5180
Oracle pid: 13, Unix process pid: 5180, image: oracle@localhost.localdomain (D000)
sys@ORCL> oradebug short_stack
ksdxfstk()+19<-ksdxcb()+1321<-sspuser()+81<-__kernel_vsyscall()+2<-ntevpque()+112<-ntevque()+606<-nsevwait()+821<-ksnwait()+111<-ksliwat()+770<-kslwaitns()+29<-
kskthbwt()+370<-kslwait()+57<-kmdmai()+1797<-kmmrdp()+432<-opirip()+1191<-opidrv()+864<-sou2o()+113<-opimai_real()+353<-main()+111<-__libc_start_main()+220
sys@ORCL> oradebug event 10046 trace name context off
Statement processed.
当前的调用步骤在输出的顶部
7、 诊断转储
可转储类型如下:
sys@ORCL> oradebug dumplist
EVENTS
TRACE_BUFFER_ON
TRACE_BUFFER_OFF
HANGANALYZE
LATCHES
PROCESSSTATE
SYSTEMSTATE
INSTANTIATIONSTATE
.........
下面举例两个:
1)、controlf
sys@ORCL> oradebug setmypidStatement processed.
sys@ORCL> oradebug dump controlf 10
Statement processed.
sys@ORCL> oradebug tracefile_name
/u01/app/oracle/admin/orcl/udump/orcl_ora_5234.trc
2)、events
这不是真正的转储,events只是将激活的事件写入trc文件。如果你不能确定一个会话、进程或者实例中哪个事件是活动的,下面有一种很好的方式去查询:
sys@ORCL> alter session set events '10046 trace name context forever,level 8';
Session altered.
sys@ORCL> alter session set events '4031 trace name heapdump level 3';
Session altered.
sys@ORCL> oradebug setmypid
Statement processed.
sys@ORCL> oradebug dump events 1
Statement processed.
sys@ORCL> oradebug tracefile_name
/u01/app/oracle/admin/orcl/udump/orcl_ora_5234.trc
sys@ORCL> oradebug event 10046 trace name context off
Statement processed.
oradebug dump events的级别和范围:
级别
事件范围
使用的命令
1
会话
alter session
2
进程
oradebug event
4
实例
alter system
8、 小结
oradebug对于解决问题和诊断性能是必不可少的工具。本文只是讲述了一些最常见的命令,还有更多的功能,比如RAC环境下调用全局队列服务调试器的
oradebug lkdebug命令。
=====补充=====================================================
Oradebug命令它可以启动用停止跟踪任何会话,最常用的就是它可以直接通过命令输出生成trace文件的名称(带路径的),省去不少麻烦,系统HANG住用它做分析也比较好用!
1> oradebug有哪些可用命令?
以sysdba身份登陆数据库,通过oradebug help可以看到oradebug常用命令
sqlplus / as sysdba
oradebug help
2> 跟踪当前会话信息
oradebug setmypid --跟踪当前会话
oradebug setospid --跟踪系统进程
oradebug setorapid --跟踪ORACLE进程
oradebug unlimit --取消trace文件大小限制
oradebug tracefile_name --查看trace文件名及位置
3> 用oradebug做session级10046或10053
oradebug setmypid
oradebug unlimit
oradebug session_event 10046 trace name context forever ,level 4 --启用会话级10046
oradebug session_event 10046 trace name context off --关闭10046事件
oradebug tracefile_name --查看tracefile文件位置及文件名
4> 用oradebug做oracle process级10046
oradebug setorapid
oradebug unlimit
oradebug event 10046 trace name context forever ,level 4
oradebug event 10046 trace name context off
oradebug tracefile_name
5> oradebug系统hang住原因分析
如果系统HANG住,只要sys用户可以登陆,那么用oradebug做原因分析是非常有用的
oradebug setmypid
oradebug unlimit
oradebug setinst all --RAC环境
oradebug hanganalyze 3 -- 级别一般指定为3足够了
oradebug -g def dump systemstate 10 --RAC环境
oradebug tracefile_name
使用oradebug 命令 -- —针对rac的用法
ORADEBUG setmypid
oradebug unlimit
ORADEBUG setinst all
ORADEBUG -g def hanganalyze 3
oradebug close_trace
oradebug tracefile_name
对于单实例,我们通常进行如下操作即可:
oradebug setmypid
oradebug unlimit
oradebug hanganalyze 3
oradebug close_trace
oradebug tracefile_name
6> 获取某进程的状态信息
oradebug setospid 22180
oradebug dump processstate 10
oradebug tracefile_name
7> 获取进程错误信息状态
oradebug setospid 22180
oradebug dump errorstack 3
8> 追踪造成错误信息的原因,如ORA-04031
oradebug event 4031 trace name errorstack level 3
实验oradebug session级别的10046事件跟踪:
——设置当前会话pid;
SYS@ORA11GR2>oradebug setmypid
Statement processed.
——开启10046事件跟踪:
SYS@ORA11GR2>oradebug session_event 10046 trace name context forever,level 12;
Statement processed.
SYS@ORA11GR2>
——进行操作以生成trace文件:
SYS@ORA11GR2>create table t1 as select * from user_objects;
Table created.
SYS@ORA11GR2>select count(*) from t1;
COUNT(*)
----------
37793
——关闭10046事件:
SYS@ORA11GR2>oradebug session_event 10046 trace name context off;
Statement processed.
——查看跟踪问位置:
SYS@ORA11GR2>oradebug tracefile_name
/u01/app/oracle/diag/rdbms/ora11gr2/ORA11GR2/trace/ORA11GR2_ora_30254.trc
SYS@ORA11GR2>
SYS@ORA11GR2>!ls /u01/app/oracle/diag/rdbms/ora11gr2/ORA11GR2/trace/ORA11GR2_ora_30254.trc
/u01/app/oracle/diag/rdbms/ora11gr2/ORA11GR2/trace/ORA11GR2_ora_30254.trc
验证:
[oracle@wang trace]$ pwd
/u01/app/oracle/diag/rdbms/ora11gr2/ORA11GR2/trace
[oracle@wang trace]$ ls –lrt
-rw-r----- 1 oracle oinstall 91 Oct 15 23:10 ORA11GR2_mmon_27426.trm
-rw-r----- 1 oracle oinstall 1172 Oct 15 23:10 ORA11GR2_mmon_27426.trc
-rw-r----- 1 oracle oinstall 2344 Oct 15 23:11 ORA11GR2_ora_30254.trm
-rw-r----- 1 oracle oinstall 895526 Oct 15 23:11 ORA11GR2_ora_30254.trc
[oracle@wang trace]$
——利用tkprof工具格式化trace文件:
[oracle@wang trace]$ tkprof ORA11GR2_ora_30254.trc 2311
TKPROF: Release 11.2.0.4.0 - Development on Sat Oct 15 23:15:21 2016
Copyright (c) 1982, 2011, Oracle and/or its affiliates. All rights reserved.
验证:
[oracle@wang trace]$ ls 2311.prf
2311.prf
[oracle@wang trace]$
——查看生成的trace文件:
[oracle@wang trace]$ cat 2311.prf
省略………………………..
*******************************************************************************
SQL ID: f9cw1jf3v3nvk Plan Hash: 1647312333
create table t1 as select
call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 1 0.02 0.01 0 0 0 0
Execute 1 0.21 0.25 0 1371 758 37793
Fetch 0 0.00 0.00 0 0 0 0
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 2 0.23 0.27 0 1371 758 37793
Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: SYS
Number of plan statistics captured: 1
Rows (1st) Rows (avg) Rows (max) Row Source Operation
---------- ---------- ---------- ---------------------------------------------------
0 0 0 LOAD AS SELECT (cr=1559 pr=0 pw=514 time=255298 us)
37793 37793 37793 VIEW USER_OBJECTS (cr=1350 pr=0 pw=0 time=187371 us cost=312 size=538650 card=2835)
37793 37793 37793 UNION-ALL (cr=1350 pr=0 pw=0 time=177792 us)
0 0 0 TABLE ACCESS BY INDEX ROWID SUM$ (cr=0 pr=0 pw=0 time=0 us cost=1 size=9 card=1)
0 0 0 INDEX UNIQUE SCAN I_SUM$_1 (cr=0 pr=0 pw=0 time=0 us cost=0 size=0 card=1)(object id 1035)
0 0 0 TABLE ACCESS BY INDEX ROWID OBJ$ (cr=0 pr=0 pw=0 time=0 us cost=3 size=30 card=1)
0 0 0 INDEX RANGE SCAN I_OBJ1 (cr=0 pr=0 pw=0 time=0 us cost=2 size=0 card=1)(object id 36)
37793 37793 37793 FILTER (cr=1349 pr=0 pw=0 time=108189 us)
37793 37793 37793 HASH JOIN (cr=1078 pr=0 pw=0 time=39878 us cost=295 size=317894 card=2999)
92 92 92 INDEX FULL SCAN I_USER2 (cr=1 pr=0 pw=0 time=10 us cost=1 size=2024 card=92)(object id 47)
37793 37793 37793 TABLE ACCESS FULL OBJ$ (cr=1077 pr=0 pw=0 time=22663 us cost=294 size=251916 card=2999)
1251 1251 1251 TABLE ACCESS BY INDEX ROWID IND$ (cr=271 pr=0 pw=0 time=4705 us cost=2 size=8 card=1)
1251 1251 1251 INDEX UNIQUE SCAN I_IND1 (cr=52 pr=0 pw=0 time=1233 us cost=1 size=0 card=1)(object id 41)
0 0 0 NESTED LOOPS (cr=0 pr=0 pw=0 time=0 us cost=2 size=29 card=1)
0 0 0 INDEX SKIP SCAN I_USER2 (cr=0 pr=0 pw=0 time=0 us cost=1 size=20 card=1)(object id 47)
0 0 0 INDEX RANGE SCAN I_OBJ4 (cr=0 pr=0 pw=0 time=0 us cost=1 size=9 card=1)(object id 39)
0 0 0 TABLE ACCESS BY INDEX ROWID LINK$ (cr=1 pr=0 pw=0 time=19 us cost=0 size=88 card=1)
0 0 0 INDEX RANGE SCAN I_LINK1 (cr=1 pr=0 pw=0 time=18 us cost=0 size=0 card=1)(object id 138)
Elapsed times include waiting on following events:
Event waited on Times Max. Wait Total Waited
---------------------------------------- Waited ---------- ------------
Disk file operations I/O 2 0.00 0.00
direct path write 18 0.00 0.00
direct path sync 1 0.04 0.04
log file sync 1 0.00 0.00
SQL*Net message to client 1 0.00 0.00
SQL*Net message from client 1 0.00 0.00
**************************************************************************************************************************************************************
SQL ID: 5bc0v4my7dvr5 Plan Hash: 3724264953
select count(*)
from
t1
call count cpu elapsed disk query current rows
------- ------ -------- ---------- ---------- ---------- ---------- ----------
Parse 1 0.00 0.00 0 1 0 0
Execute 1 0.00 0.00 0 0 0 0
Fetch 2 0.00 0.00 120 518 0 1
------- ------ -------- ---------- ---------- ---------- ---------- ----------
total 4 0.00 0.00 120 519 0 1
Misses in library cache during parse: 1
Optimizer mode: ALL_ROWS
Parsing user id: SYS
Number of plan statistics captured: 1
Rows (1st) Rows (avg) Rows (max) Row Source Operation
---------- ---------- ---------- ---------------------------------------------------
1 1 1 SORT AGGREGATE (cr=518 pr=120 pw=0 time=4122 us)
37793 37793 37793 TABLE ACCESS FULL T1 (cr=518 pr=120 pw=0 time=7913 us cost=141 size=0 card=37628)
Elapsed times include waiting on following events:
Event waited on Times Max. Wait Total Waited
---------------------------------------- Waited ---------- ------------
SQL*Net message to client 2 0.00 0.00
db file scattered read 20 0.00 0.00
db file sequential read 1 0.00 0.00
SQL*Net message from client 2 0.00 0.00
***********************************************************************