oracle編程藝術--runstst工具

時間 2019-11-16

標籤 oracle 編程藝術 runstst 工具欄目 Oracle 简体版

原文原文鏈接

runstats工具是《 oracle database 9i/10g/11g編程藝術深刻數據庫體系結構》做者寫的一個統計性能工具，能對作同一件事的兩個方法進行比較，獲得孰優孰劣的結果。sql

(看到runstats想到了db2 裏有runstats命令收集統計信息)數據庫

runststs工具主要測量三個要素編程

牆上時鐘（wall clock) 或耗用時間（elapsed time)
系通通計結果，會並排地顯示每一個方法作某件事（如執行一個解析調用）的次數，並展現出兩者之差
閂定（latch）這個是報告的關鍵輸出

要使用該工具，須要能訪問V$視圖，並建立一個表來存儲統計結果，還須要建立runstats包，下面是在scott用戶下建立該工具，如下試驗在ORACLE 11.2.0.1.0上進行c#

使用SYS用戶登陸，執行如下語句session

--默認scott無建立視圖權限,建立視圖時會報ORA-01031: insufficient privileges
grant create view to scott;
--將如下4個動態性能視圖原表SELECT權限賦給scott
grant SELECT on v_$statname to scott ;
grant SELECT on v_$mystat to scott ;
grant SELECT on v_$latch to scott ;
grant SELECT on v_$timer to scott ;

scott用戶下登陸，執行如下語句oracle

--建立統計結果表
create or replace view stats
as select 'STAT...' || a.name name, b.value 
      from sys.v_$statname a, sys.v_$mystat b 
     where a.statistic# = b.statistic# 
    union all 
    select 'LATCH.' || name,  gets 
      from sys.v_$latch 
    union all 
    select 'STAT...Elapsed Time', hsecs from sys.v_$timer; 

--建立臨時表收集統計結果
create global temporary table run_stats
(runid varchar2(15),
 name varchar2(80),
 value int)
 on commit preserve rows;  
    
--建立runstats包
-- runstats包含3個API，runstats測試開始時調用rs_start，rs_middle會在測試中調用，完成時調用rs_stop，打印報告
-- rs_stop的p_difference_threshold參數，用來控制最後打印的數據量，輸入這個參數可只查看差值大於參數的統計結果和閂信息，默認爲0所有顯示

create or replace package runstats_pkg
as 
       procedure rs_start;
       procedure rs_middle;
       procedure rs_stop（p_difference_threshold in number default 0);
end ;
/

create or replace package body runstats_pkg 
    as 
    g_start number; 
    g_run1  number; 
    g_run2  number; 
    
    --清空統計結果表，插入上一次統計結果，獲取當前定時器值
    procedure rs_start 
    is 
    begin 
       delete from run_stats; 
       
       insert into run_stats 
       select 'before', stats.* from stats; 
         g_start := dbms_utility.get_cpu_time; 
   end; 
 
   procedure rs_middle 
   is 
   begin 
       g_run1 := (dbms_utility.get_cpu_time-g_start); 
  
       insert into run_stats 
       select 'after 1', stats.* from stats; 
       g_start := dbms_utility.get_cpu_time; 
  
   end; 

   --打印每次運行累計CPU時間，分別打印兩次運行的統計結果和閂值（只打印超過p_difference_threshold的結果）
   procedure rs_stop(p_difference_threshold in number default 0) 
   is 
   begin 
       g_run2 := (dbms_utility.get_cpu_time-g_start); 
  
       dbms_output.put_line 
       ( 'Run1 ran in ' || g_run1 || ' cpu hsecs' ); 
       dbms_output.put_line 
       ( 'Run2 ran in ' || g_run2 || ' cpu hsecs' ); 
           if ( g_run2 <> 0 ) 
           then 
       dbms_output.put_line 
       ( 'run 1 ran in ' || round(g_run1/g_run2*100,2) || 
         '% of the time' ); 
           end if; 
       dbms_output.put_line( chr(9) ); 
  
       insert into run_stats 
       select 'after 2', stats.* from stats; 
  
       dbms_output.put_line 
       ( rpad( 'Name', 30 ) || lpad( 'Run1', 12 ) || 
         lpad( 'Run2', 12 ) || lpad( 'Diff', 12 ) ); 
  
       for x in 
       ( select rpad( a.name, 30 ) || 
                to_char( b.value-a.value, '999,999,999' ) || 
                to_char( c.value-b.value, '999,999,999' ) || 
                 to_char( ( (c.value-b.value)-(b.value-a.value)),  
                                    '999,999,999' ) data 
           from run_stats a, run_stats b, run_stats c 
          where a.name = b.name 
            and b.name = c.name 
            and a.runid = 'before' 
            and b.runid = 'after 1' 
            and c.runid = 'after 2' 
             
            and abs( (c.value-b.value) - (b.value-a.value) ) 
                  > p_difference_threshold 
          order by abs( (c.value-b.value)-(b.value-a.value)) 
       ) loop 
           dbms_output.put_line( x.data ); 
       end loop; 
  
       dbms_output.put_line( chr(9) ); 
       dbms_output.put_line 
       ( 'Run1 latches total versus runs -- difference and pct' ); 
       dbms_output.put_line 
       ( lpad( 'Run1', 12 ) || lpad( 'Run2', 12 ) || 
         lpad( 'Diff', 12 ) || lpad( 'Pct', 10 ) ); 
  
       for x in 
       ( select to_char( run1, '999,999,999' ) || 
                to_char( run2, '999,999,999' ) || 
                to_char( diff, '999,999,999' ) || 
                to_char( round( run1/decode( run2, 0,  
                             to_number(0), run2) *100,2 ), '99,999.99' ) || '%' data 
           from ( select sum(b.value-a.value) run1, sum(c.value-b.value) run2, 
                         sum( (c.value-b.value)-(b.value-a.value)) diff 
                    from run_stats a, run_stats b, run_stats c 
                   where a.name = b.name 
                    and b.name = c.name 
                     and a.runid = 'before' 
                     and b.runid = 'after 1' 
                     and c.runid = 'after 2' 
                     and a.name like 'LATCH%' 
                   ) 
       ) loop 
           dbms_output.put_line( x.data ); 
       end loop; 
   end; 
  
  end; 
  /

工具建立好了以後，能夠拿個例子來測試一下，把下面語句寫入test.sql，作成一個SQL文件,sqlplus中執行工具

drop table testStat; create table testStat(id varchar2(10)); exec runstats_pkg.rs_start; exec dbms_output.put_line('rs_start....'); insert into testStat select level from dual connect by level <=500000; commit; exec dbms_output.put_line('insert completed....'); exec runstats_pkg.rs_middle; exec dbms_output.put_line('rs_middle....'); begin for i in 1 .. 500000 loop insert into testStat values (i); end loop; commit; end; / exec dbms_output.put_line('loop insert....'); exec runstats_pkg.rs_stop(0);

結果以下：oop

[oracle@RHEL65 test]$ sqlplus scott/oracle@orcl @t.sql

SQL*Plus: Release 11.2.0.1.0 Production on Sat Jan 30 16:54:09 2016

Copyright (c) 1982, 2009, Oracle.  All rights reserved.


Connected to:
Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production
With the Partitioning, OLAP, Data Mining and Real Application Testing options


Table dropped.


Table created.


PL/SQL procedure successfully completed.

rs_start....

PL/SQL procedure successfully completed.


500000 rows created.


Commit complete.

insert completed....

PL/SQL procedure successfully completed.


PL/SQL procedure successfully completed.

rs_middle....

PL/SQL procedure successfully completed.


PL/SQL procedure successfully completed.

loop insert....

PL/SQL procedure successfully completed.

Run1 ran in 66 cpu hsecs
Run2 ran in 2217 cpu hsecs
run 1 ran in 2.98% of the time

Name                                  Run1        Run2        Diff
STAT...opened cursors current           -1           0           1
STAT...redo synch writes                 2           1          -1
STAT...commit txn count during           2           3           1
STAT...IMU Flushes                       2           1          -1
STAT...rows fetched via callba           5           4          -1
STAT...cursor authentications            0           1           1
STAT...buffer is pinned count            1           2           1
STAT...parse time elapsed                1           0          -1
LATCH.channel handle pool latc           2           1          -1
LATCH.queued dump request                0           1           1
LATCH.MinActiveScn Latch                 0           1           1
LATCH.Shared B-Tree                      1           2           1
LATCH.hash table modification            1           0          -1
LATCH.SQL memory manager latch           0           1           1
LATCH.kwqbsn:qsga                        0           1           1
LATCH.threshold alerts latch             0           1           1
STAT...IMU pool not allocated            0           2           2
STAT...IMU- failed to get a pr           0           2           2
STAT...SQL*Net roundtrips to/f          10           8          -2
LATCH.ksuosstats global area             0           2           2
LATCH.dml lock allocation                2           4           2
STAT...user calls                       15          12          -3
STAT...sorts (memory)                   11           8          -3
STAT...sorts (rows)                      5           2          -3
LATCH.object stats modificatio           7           4          -3
LATCH.kcbtsemkid latch                   0           3           3
LATCH.managed standby latch              0           3           3
LATCH.parameter list                     0           3           3
LATCH.session state list latch           3           0          -3
LATCH.session switching                  1           5           4
LATCH.ksv allocation latch               0           4           4
LATCH.sort extent pool                   0           4           4
LATCH.deferred cleanup latch             0           4           4
LATCH.cp sga latch                       0           4           4
LATCH.parallel query alloc buf           1           5           4
LATCH.ncodef allocation latch            0           4           4
LATCH.qmn task queue latch               0           4           4
LATCH.ASM network state latch            0           4           4
STAT...write clones created in           0           5           5
STAT...immediate (CURRENT) blo          14           9          -5
LATCH.resmgr:active threads              0           5           5
LATCH.resmgr:schema config               0           5           5
LATCH.job_queue_processes para           0           5           5
STAT...table scans (short tabl           6          12           6
STAT...table scan blocks gotte           4          10           6
LATCH.FAL Queue                          0           6           6
LATCH.alert log latch                    0           6           6
LATCH.reservation so alloc lat           0           6           6
LATCH.transaction allocation            15           8          -7
LATCH.OS process allocation              0           9           9
LATCH.KMG MMAN ready and start           0           9           9
LATCH.Change Notification Hash           0           9           9
LATCH.Real-time plan statistic           0           9           9
STAT...redo buffer allocation            0          10          10
STAT...physical read total IO            2          13          11
STAT...physical reads                    2          13          11
STAT...physical reads cache              2          13          11
STAT...physical read IO reques           2          13          11
LATCH.cache buffer handles           1,064       1,076          12
LATCH.archive control                    0          12          12
LATCH.Reserved Space Latch               0          12          12
LATCH.session timer                      0          12          12
LATCH.kks stats                          1          15          14
LATCH.shared pool simulator             10          24          14
STAT...Heap Segment Array Upda          23           8         -15
STAT...switch current to new b          31          14         -17
STAT...calls to get snapshot s         188         171         -17
STAT...cluster key scans                51          34         -17
STAT...cluster key scan block           51          34         -17
STAT...index scans kdiixs1              88         105          17
STAT...deferred (CURRENT) bloc          32          14         -18
LATCH.FIB s.o chain latch                0          18          18
STAT...consistent changes               63          44         -19
STAT...table fetch by rowid             31          50          19
STAT...shared hash latch upgra          50          70          20
LATCH.archive process latch              0          21          21
LATCH.space background task la           0          21          21
STAT...consistent gets - exami         888         912          24
STAT...index fetch by key               52          28         -24
LATCH.FOB s.o list latch                 2          26          24
STAT...commit cleanouts                867         842         -25
STAT...commit cleanouts succes         861         836         -25
STAT...no work - consistent re         146         171          25
STAT...workarea memory allocat         -46         -21          25
LATCH.session idle bit                  35          63          28
STAT...hot buffers moved to he           0          29          29
LATCH.In memory undo latch              15          45          30
STAT...buffer is not pinned co         277         312          35
STAT...redo log space requests           0          40          40
LATCH.SGA IO buffer pool latch           2          45          43
LATCH.DML lock allocation              139          93         -46
LATCH.post/wait queue                    3          51          48
LATCH.active service list                0          51          51
LATCH.file cache latch                  46         108          62
STAT...cleanout - number of kt         744         817          73
STAT...active txn count during         743         816          73
LATCH.call allocation                    8          82          74
LATCH.active checkpoint queue            9          84          75
LATCH.session allocation                12         115         103
LATCH.ASM db client latch                2         106         104
LATCH.object queue header heap          22         132         110
LATCH.Consistent RBA                    15         129         114
LATCH.lgwr LWN SCN                      15         129         114
LATCH.mostly latch-free SCN             15         131         116
STAT...table scan rows gotten           50         176         126
LATCH.message pool operations            4         130         126
STAT...enqueue releases                280         409         129
STAT...enqueue requests                280         409         129
STAT...enqueue conversions               3         147         144
LATCH.JS queue state obj latch           0         180         180
STAT...messages sent                    14         202         188
STAT...file io wait time                66         276         210
STAT...non-idle wait count              21         251         230
STAT...redo log space wait tim           0         355         355
STAT...IMU undo allocation siz         712       1,080         368
STAT...change write time                 3         403         400
LATCH.redo writing                      49         486         437
STAT...bytes sent via SQL*Net        2,379       1,894        -485
LATCH.row cache objects              1,062       1,577         515
STAT...non-idle wait time                2         534         532
LATCH.SQL memory manager worka           7         610         603
LATCH.channel operations paren          15         694         679
LATCH.redo allocation                   52         751         699
STAT...bytes received via SQL*       3,942       3,228        -714
STAT...calls to kcmgcs               1,795       1,027        -768
STAT...consistent gets from ca       2,060       1,290        -770
STAT...consistent gets               2,060       1,290        -770
STAT...consistent gets from ca       1,087         287        -800
STAT...db block gets from cach       2,252       1,216      -1,036
LATCH.messages                          63       1,136       1,073
LATCH.enqueues                         348       1,422       1,074
LATCH.enqueue hash chains              566       1,722       1,156
STAT...recursive cpu usage               5       1,912       1,907
STAT...CPU used when call star          66       2,217       2,151
STAT...CPU used by this sessio          66       2,217       2,151
STAT...Elapsed Time                     68       2,751       2,683
STAT...DB time                          67       2,751       2,684
STAT...Heap Segment Array Inse       2,779          14      -2,765
STAT...free buffer requested         1,063       5,148       4,085
STAT...redo subscn max counts        1,021       5,124       4,103
STAT...calls to kcmgas                 300       4,409       4,109
STAT...redo ordering marks             233       4,369       4,136
LATCH.simulator lru latch              114       4,520       4,406
LATCH.undo global data               1,065       5,517       4,452
STAT...free buffer inspected             0       4,479       4,479
LATCH.cache buffers lru chain          187       5,401       5,214
LATCH.checkpoint queue latch           221       6,512       6,291
STAT...IMU Redo allocation siz         540      11,212      10,672
LATCH.object queue header oper       2,563      25,027      22,464
LATCH.simulator hash latch             949      36,561      35,612
STAT...session cursor cache hi          78      41,324      41,246
STAT...physical read bytes          16,384     106,496      90,112
STAT...cell physical IO interc      16,384     106,496      90,112
STAT...physical read total byt      16,384     106,496      90,112
STAT...HSC Heap Segment Block        2,813     500,029     497,216
STAT...redo entries                  6,853     504,649     497,796
STAT...recursive calls               1,443     501,196     499,753
STAT...execute count                   172     500,170     499,998
STAT...opened cursors cumulati         159     500,162     500,003
LATCH.shared pool                      219     500,325     500,106
STAT...session logical reads        12,488     516,953     504,465
STAT...db block gets                10,428     515,663     505,235
STAT...db block gets from cach      10,428     515,663     505,235
STAT...session pga memory          524,288    -196,608    -720,896
STAT...session uga memory          785,856    -196,464    -982,320
STAT...db block changes             10,145   1,009,164     999,019
LATCH.cache buffers chains          41,525   2,576,396   2,534,871
STAT...session pga memory max   24,772,608           0 -24,772,608
STAT...session uga memory max   24,843,448           0 -24,843,448
STAT...undo change vector size   1,210,048  34,006,444  32,796,396
STAT...redo size                 8,857,832 124,592,660 115,734,828

Run1 latches total versus runs -- difference and pct
Run1        Run2        Diff       Pct
50,679   3,171,932   3,121,253      1.60%

PL/SQL procedure successfully completed.

scott@ORCL>