在监控、诊断、处理数据库性能问题的时候,时间信息往往是非常重要的判断依据。有时候可能我们会使用一些比例来判断性能,但是使用比例而不使用时间往往会将我们带向错误的方向。在Oracle9i的第一版中关于时间的信息被进行了增强,提供了更多更有益的时间信息。除了9i的外貌发生了变化,在一些并没有被我们注意或者不为人知得一些v$视图的相关字段也发生了变化。这里提到的主要是他们发生了那些改变以及对DBA有什么帮助。这里也列出了零星的一些Oracle内部的未公开的信息。
Second 1 秒
Centi-second 1/100 百分之一秒 厘秒
Milli Second 1/1000 千分之一秒 毫秒
Micro Second 1/1.000.000 微妙
Nano Second 1/1.000.000.000 纳秒
Centi-second 1/100 百分之一秒 厘秒
Milli Second 1/1000 千分之一秒 毫秒
Micro Second 1/1.000.000 微妙
Nano Second 1/1.000.000.000 纳秒
在以前的版本中,Oracle的时间计量单位是厘秒,使用厘秒最显而易见的问题就是可能有些操作是小于厘秒的。看上去这似乎不太常见,但是实际上在操作系统上很多操作都是以微妙作为单位的,这意味着操作的起始和终止在不到厘秒就完成了,从厘秒级看就好像没有发生一样,因为持续时间近似为0。而有时候操作的持续时间不到厘秒,但是起始和终止发生在两个相连的厘秒,所以操作时间不到厘秒但是却被记录为厘秒,造成时间记录的不准确。
在Oracle9i之前,最小的时间单位仍然是厘秒,这可以在跟踪文件、v$system_event和v$session_event中的超时字段看到。在9i之前,是不能在联机状态看到sql语句的执行(cpu消耗)时间和持续时间的,也不能看到一条Sql语句在等待着什么,实际上只有一种方法可以得到这些信息,就是通过启用10046 trace level 12的跟踪事件。这样做也会带来下面的一些问题:
1. 生成进程跟踪文件带来很高的性能开销
2. 如果有太多用于帮助调优的sql语句执行,将会产生大量的磁盘/文件空间需求。
在Oracle9i之前,最小的时间单位仍然是厘秒,这可以在跟踪文件、v$system_event和v$session_event中的超时字段看到。在9i之前,是不能在联机状态看到sql语句的执行(cpu消耗)时间和持续时间的,也不能看到一条Sql语句在等待着什么,实际上只有一种方法可以得到这些信息,就是通过启用10046 trace level 12的跟踪事件。这样做也会带来下面的一些问题:
1. 生成进程跟踪文件带来很高的性能开销
2. 如果有太多用于帮助调优的sql语句执行,将会产生大量的磁盘/文件空间需求。
在oracle9i第一版中
在oracle9i第一版中的持续时间以微妙作为时间单位,这能够显示出Oracle真正花费的时间。超时仍然以微妙作为计时单位。一些视图被扩展以便记录微妙数据。所有的时间信息由初始化参数timed_statistics 决定。
在oracle9i第一版中的持续时间以微妙作为时间单位,这能够显示出Oracle真正花费的时间。超时仍然以微妙作为计时单位。一些视图被扩展以便记录微妙数据。所有的时间信息由初始化参数timed_statistics 决定。
一些视图的改变:
V$SQLAREA:
两个字段被加入到V$SQLAREA中,分别是CPU_TIME 和ELAPSED_TIME。两个字段都被设置为微妙级。然而,在这里可能会s看到一个有趣的现象:
Select cpu_time, elapsed_time
From v$sqlarea
Order by 2
CPU_TIME ELAPSED_TIME
---------- ------------
230000 174567
260000 258803
260000 271808
首先:三行中的第二行显示出了持续时间小于cpu执行时间,实际的情况是cpu字段记录单位是微妙级,但是数据被进位保留到了厘秒级。
V$SQLAREA:
两个字段被加入到V$SQLAREA中,分别是CPU_TIME 和ELAPSED_TIME。两个字段都被设置为微妙级。然而,在这里可能会s看到一个有趣的现象:
Select cpu_time, elapsed_time
From v$sqlarea
Order by 2
CPU_TIME ELAPSED_TIME
---------- ------------
230000 174567
260000 258803
260000 271808
首先:三行中的第二行显示出了持续时间小于cpu执行时间,实际的情况是cpu字段记录单位是微妙级,但是数据被进位保留到了厘秒级。
Sql语句的等待时间等于ELAPSED_TIME减去CPU_TIME,但是很难看到精确的等待时间。在V$SYSTEM_EVENT 视图中能够看到数据库实例级的等待时间(并不是每条Sql语句的),但是看不到发生在操作系统上(cpu等待、内存的等待)的等待时间s。
X$KSQST的改变 (对应视图V$ENQUEUE_STAT)
基础表X$KSQST主要显示关于系统中队列的信息。在9i之前只能提供队列类型的get和wait数。一个队列有两种特征和两个标识组成(id1 and id2)。例如一个叫做TX队列,表示一个事务队列。从Oracle9i开始不但能够看到gets 和waits,而且等待的时间和得不到队列(中断或者超时)的失败次数都可以看到。
X$KSQST
KSQSTWTIM NUMBER 以微妙作为单位
KSQSTWTIM NUMBER 以微妙作为单位
然而这里仍不能将队列等待与Sql语句关联,也不能够得到等待队列的Sql语句到底等待了多久。下面的一个新的视图v$enqueue_stat 将提供关于队列得更多信息:
V$ENQUEUE_STAT
INST_ID NUMBER
EQ_TYPE VARCHAR2
TOTAL_REQ# NUMBER
TOTAL_WAIT# NUMBER
SUCC_REQ# NUMBER
FAILED_REQ# NUMBER
CUM_WAIT_TIME NUMBER
INST_ID NUMBER
EQ_TYPE VARCHAR2
TOTAL_REQ# NUMBER
TOTAL_WAIT# NUMBER
SUCC_REQ# NUMBER
FAILED_REQ# NUMBER
CUM_WAIT_TIME NUMBER
V$SYSSTAT的改变
实际这个视图并没有什么改变,关于时间的信息仍然在厘秒级。
实际这个视图并没有什么改变,关于时间的信息仍然在厘秒级。
V$SYSSTAT
CPU used by this session 厘秒
Parse time cpu 厘秒
Parse time 厘秒
Recursive cpu usage 厘秒
当从不同资源中比较数据时应该注意。
CPU used by this session 厘秒
Parse time cpu 厘秒
Parse time 厘秒
Recursive cpu usage 厘秒
当从不同资源中比较数据时应该注意。
V$WAITSTAT的改变
这个视图显示了发生在块级的buffer busy waits的细目描述,等待时间的单位在微妙级。
这个视图显示了发生在块级的buffer busy waits的细目描述,等待时间的单位在微妙级。
V$WAITSTAT
TIME NUMBER 厘秒单位
这里无法通过视图中出现的等待找到对应的sql语句。
TIME NUMBER 厘秒单位
这里无法通过视图中出现的等待找到对应的sql语句。
V$FILESTAT的改变
在这个视图中现在分成了单次块读取和累计块读取,这种分法更合理。
在这个视图中现在分成了单次块读取和累计块读取,这种分法更合理。
V$FILESTAT
MAXIORTM NUMBER 厘秒级的最大读取时间
MAXIOWTM NUMBER 厘秒级的最大写入时间
SINGLEBLKRDS NUMBER 单次块读取块数
SINGLEBLKRDTIM NUMBER 厘秒级的累计单次块读取时间
这个视图也不能找到相关的Sql语句,使用dbms_system.kcfrms()过程可以重设最大读、写时间,设置更准确的取样间隔。
MAXIORTM NUMBER 厘秒级的最大读取时间
MAXIOWTM NUMBER 厘秒级的最大写入时间
SINGLEBLKRDS NUMBER 单次块读取块数
SINGLEBLKRDTIM NUMBER 厘秒级的累计单次块读取时间
这个视图也不能找到相关的Sql语句,使用dbms_system.kcfrms()过程可以重设最大读、写时间,设置更准确的取样间隔。
V$SESSION_EVENT的改变
这个视图现在已经可以捕捉到微妙级的等待事件的持续时间了。它可以以微妙或者厘秒方式显示等待时间,因为这些改变,等待事件变得更加精确(等待时间以微妙方式捕捉,然后转换为厘秒方式)
这个视图现在已经可以捕捉到微妙级的等待事件的持续时间了。它可以以微妙或者厘秒方式显示等待时间,因为这些改变,等待事件变得更加精确(等待时间以微妙方式捕捉,然后转换为厘秒方式)
V$SESSION_EVENT
TIME_WAITED_MICRO NUMBER 以微妙级等待的时间
TIME_WAITED NUMBER 微妙级的等待时间 / 10000
MAX_WAIT NUMBER 厘秒级的最大等待时间
TIME_WAITED_MICRO NUMBER 以微妙级等待的时间
TIME_WAITED NUMBER 微妙级的等待时间 / 10000
MAX_WAIT NUMBER 厘秒级的最大等待时间
当然,这个视图也不能找到相关的Sql语句。
一个被大多数DBA所感兴趣的字段是MAX_WAIT 字段,这个字段显示了一个事件的最大等待时间。这个信息只要会话一登陆就生效,将显示一个会话整个生命期中的最大等待时间。使用dbms_system.kcfrms() 过程可以重置最大的值,以便让这个字段显示与测量周期更紧密的值。
一个被大多数DBA所感兴趣的字段是MAX_WAIT 字段,这个字段显示了一个事件的最大等待时间。这个信息只要会话一登陆就生效,将显示一个会话整个生命期中的最大等待时间。使用dbms_system.kcfrms() 过程可以重置最大的值,以便让这个字段显示与测量周期更紧密的值。
V$SYSTEM_EVENT的改变
这个视图最主要的变化是增加了TIME_WAITED_MICRO字段,它以微妙为单位显示了持续的时间。.另外TIME_WAITED 也更加精确,,因为它来自于TIME_WAITED_MICRO的值。
这个视图最主要的变化是增加了TIME_WAITED_MICRO字段,它以微妙为单位显示了持续的时间。.另外TIME_WAITED 也更加精确,,因为它来自于TIME_WAITED_MICRO的值。
V$SYSTEM_EVENT
TIME_WAITED_MICRO NUMBER 持续的等待时间(微妙)
TIME_WAITED NUMBER 从time_waited_micro取值,以厘秒为单位
TIME_WAITED_MICRO NUMBER 持续的等待时间(微妙)
TIME_WAITED NUMBER 从time_waited_micro取值,以厘秒为单位
SQL TRACE
通过在会话级设置事件‘10046 trace name context level 12, forever’可以看到下面的跟踪信息。
PARSING IN CURSOR #1 len=29 dep=0 uid=5 oct=3 lid=5 tim=1006862415399006 hv=4384
51932 ad='84b05c04'
select count(*) from tab, tab
END OF STMT
PARSE #1:c=10000,e=11334,p=0,cr=4,cu=0,mis=1,r=0,dep=0,og=4,tim=1006862415398976
BINDS #1:
EXEC #1:c=0,e=587,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=4,tim=1006862415399931
WAIT #1: nam='SQL*Net message to client' ela= 10 p1=1650815232 p2=1 p3=0
FETCH #1:c=2500000,e=2526458,p=0,cr=149542,cu=0,mis=0,r=1,dep=0,og=4,tim=1006862
417926777
WAIT #1: nam='SQL*Net message from client' ela= 1004 p1=1650815232 p2=1 p3=0
FETCH #1:c=0,e=6,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=1006862417928426
WAIT #1: nam='SQL*Net message to client' ela= 7 p1=1650815232 p2=1 p3=0
WAIT #1: nam='SQL*Net message from client' ela= 3636276 p1=1650815232 p2=1 p3=0
注意:时间都是微妙级的,但是这里仍然有一些很有意思的地方:
通过在会话级设置事件‘10046 trace name context level 12, forever’可以看到下面的跟踪信息。
PARSING IN CURSOR #1 len=29 dep=0 uid=5 oct=3 lid=5 tim=1006862415399006 hv=4384
51932 ad='84b05c04'
select count(*) from tab, tab
END OF STMT
PARSE #1:c=10000,e=11334,p=0,cr=4,cu=0,mis=1,r=0,dep=0,og=4,tim=1006862415398976
BINDS #1:
EXEC #1:c=0,e=587,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=4,tim=1006862415399931
WAIT #1: nam='SQL*Net message to client' ela= 10 p1=1650815232 p2=1 p3=0
FETCH #1:c=2500000,e=2526458,p=0,cr=149542,cu=0,mis=0,r=1,dep=0,og=4,tim=1006862
417926777
WAIT #1: nam='SQL*Net message from client' ela= 1004 p1=1650815232 p2=1 p3=0
FETCH #1:c=0,e=6,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=1006862417928426
WAIT #1: nam='SQL*Net message to client' ela= 7 p1=1650815232 p2=1 p3=0
WAIT #1: nam='SQL*Net message from client' ela= 3636276 p1=1650815232 p2=1 p3=0
注意:时间都是微妙级的,但是这里仍然有一些很有意思的地方:
1. tim= 明显是微妙级的,这个tim 域给出了一个时间快照
2. e= 和 ela= 也是微妙级的,这两个域表示持续的事件
3. 比较有趣的是c=. 看上去好像是微妙级的,但是实际上是厘秒级的数据乘上了10000, 域c 表示cpu的消耗
2. e= 和 ela= 也是微妙级的,这两个域表示持续的事件
3. 比较有趣的是c=. 看上去好像是微妙级的,但是实际上是厘秒级的数据乘上了10000, 域c 表示cpu的消耗
v$latch, / v$latch_parent / v$latch_children 的改变:
在Oracle9i主要的改变是增加了wait_time字段。因此现在锁存器竞争更容易检测了,而在Oracle9i以前,只能通过察看sleeps次数来判断。在v$latch中主要相关的字段包括:
V$LATCH
NAME VARCHAR(64) 锁存器名称.
SLEEPS NUMBER 锁存器由于忙而产生的sleep次数
SLEEP1..11 NUMBER 显示了sleep的分布情况
WAIT_TIME NUMBER 微妙级的等待时间
在Oracle9i主要的改变是增加了wait_time字段。因此现在锁存器竞争更容易检测了,而在Oracle9i以前,只能通过察看sleeps次数来判断。在v$latch中主要相关的字段包括:
V$LATCH
NAME VARCHAR(64) 锁存器名称.
SLEEPS NUMBER 锁存器由于忙而产生的sleep次数
SLEEP1..11 NUMBER 显示了sleep的分布情况
WAIT_TIME NUMBER 微妙级的等待时间
在Orace9i以前与v$latch的 ‘latch free’等待事件的SLEEPS字段对应的是v$system_event的TOTAL_WAITS字段:
select event, total_waits, time_waited
from v$system_event
where event = 'latch free'
select event, total_waits, time_waited
from v$system_event
where event = 'latch free'
select name, trunc(sleeps*100/ total, 2) "Perc of Sleeps"
from v$latch, (select decode(sum(sleeps), 0, 1, sum(sleeps)) total from v$latch)
where sleeps != 0
from v$latch, (select decode(sum(sleeps), 0, 1, sum(sleeps)) total from v$latch)
where sleeps != 0
有很多sleeps的锁存器会显示为v$system_event的大量等待时间。在Oracle9i由于在v$latch中有了WAIT_TIME字段,所以提供了更准确的信息:
select name, trunc(wait_time*100/time_waited, 2) "Perc. of Time Waited"
from v$latch, (select time_waited from v$system_event where event = 'latch free')
where wait_time != 0
order by 2
select name, trunc(wait_time*100/time_waited, 2) "Perc. of Time Waited"
from v$latch, (select time_waited from v$system_event where event = 'latch free')
where wait_time != 0
order by 2
还有那些没有改变?
其他与时间相关的信息都没有改变。在V$SYSSTAT中象 ‘CPU used by this session’, ‘parse cpu time’ and ‘recursive cpu usage’等事件仍然表示为厘秒。
其他与时间相关的信息都没有改变。在V$SYSSTAT中象 ‘CPU used by this session’, ‘parse cpu time’ and ‘recursive cpu usage’等事件仍然表示为厘秒。
Oracle没有改变这些统计信息的单位是因为这些信息可能会影响着一些依赖于他们的应用或者脚本。
Oracle的Precise/Indepth
在Oracle9i中由于使用了微妙做为单位,所以持续时间已经变得更加准确,但是仍然不能看到在一个特定的时间范围内的每个sql语句的资源消耗。了解每个sql语句的资源消耗能够帮助你确定那个sql语句是你首先应该调优的。 使用Oracle的Precise/Indepth,通过不断的监视Oracle的环境,收集用于性能分析的数据,就能够看到每条Sql语句的资源消耗情况。这些资源消耗情况最终被细分为:
在Oracle9i中由于使用了微妙做为单位,所以持续时间已经变得更加准确,但是仍然不能看到在一个特定的时间范围内的每个sql语句的资源消耗。了解每个sql语句的资源消耗能够帮助你确定那个sql语句是你首先应该调优的。 使用Oracle的Precise/Indepth,通过不断的监视Oracle的环境,收集用于性能分析的数据,就能够看到每条Sql语句的资源消耗情况。这些资源消耗情况最终被细分为:
Using CPU
CPU wait
I/O wait
Memory wait
Other host wait
Table lock wait
Row lock wait
Shared pool wait
Buffer wait
Rollback segment wait
Redo log buffer wait
Log switch and clear wait
Internal lock wait
Background process wait
Parallel query sync. Wait
Parallel query server wait
Other wait
CPU wait
I/O wait
Memory wait
Other host wait
Table lock wait
Row lock wait
Shared pool wait
Buffer wait
Rollback segment wait
Redo log buffer wait
Log switch and clear wait
Internal lock wait
Background process wait
Parallel query sync. Wait
Parallel query server wait
Other wait