首页 > Linux操作系统 > Linux操作系统 > 《剑破冰山》读书之开发常用工具
Load Profile
| Per Second | Per Transaction | |
|---|---|---|
| Redo size: | 1,122.88 | 77,285.67 |
| Logical reads: | 48.05 | 3,306.90 |
| Block changes: | 6.44 | 443.18 |
| Physical reads: | 7.55 | 519.99 |
| Physical writes: | 0.32 | 22.37 |
| User calls: | 0.41 | 28.37 |
| Parses: | 0.58 | 39.71 |
| Hard parses: | 0.04 | 2.44 |
| Sorts: | 0.43 | 29.41 |
| Logons: | 0.01 | 0.44 |
| Executes: | 1.57 | 108.13 |
| Transactions: | 0.01 |
可以看Transactions来分析系统的压力负责。
Top 5 Timed Events
| Event | Waits | Time(s) | Avg Wait(ms) | % Total Call Time | Wait Class |
|---|---|---|---|---|---|
| db file sequential read | 115,819 | 204 | 2 | 27.6 | User I/O |
| CPU time | 117 | 15.9 | |||
| db file scattered read | 10,092 | 60 | 6 | 8.1 | User I/O |
| latch: shared pool | 252 | 12 | 48 | 1.6 | Concurrency |
| control file parallel write | 10,792 | 12 | 1 | 1.6 | System I/O |
看等待时间。db file sequential read于sql执行有关,
如果日志写log_file switch等待时间产生的原因,一般是当日志组写完后,lgwr试图写下一个log file ,如果这时候数据库没有完成记录在第一个logfile中的dirty块,就会出现这个事件,标识dbwr写速度太慢,解决方法就是增加额外的dbwr或者增加日志组和日志文件大小。
| Statistic | Total | per Second | per Trans |
|---|---|---|---|
| CPU used by this session | 7,109 | 0.22 | 15.13 |
| CPU used when call started | 1,098 | 0.03 | 2.34 |
| CR blocks created | 1,388 | 0.04 | 2.95 |
| Cached Commit SCN referenced | 35,194 | 1.09 | 74.88 |
| Commit SCN cached | 38 | 0.00 | 0.08 |
| DB time | 3,334,772 | 103.09 | 7,095.26 |
| DBWR checkpoint buffers written | 5,492 | 0.17 | 11.69 |
| DBWR checkpoints | 0 | 0.00 | 0.00 |
| DBWR object drop buffers written | 0 | 0.00 | 0.00 |
| DBWR revisited being-written buffer | 0 | 0.00 | 0.00 |
| DBWR thread checkpoint buffers written | 0 | 0.00 | 0.00 |
| DBWR transaction table writes | 1,137 | 0.04 | 2.42 |
| DBWR undo block writes | 3,555 | 0.11 | 7.56 |
| IMU CR rollbacks | 14 | 0.00 | 0.03 |
| IMU Flushes | 6,595 | 0.20 | 14.03 |
| IMU Redo allocation size | 634,932 | 19.63 | 1,350.92 |
| IMU commits | 325 | 0.01 | 0.69 |
| IMU contention | 3 | 0.00 | 0.01 |
| IMU ktichg flush | 23 | 0.00 | 0.05 |
| IMU pool not allocated | 0 | 0.00 | 0.00 |
| IMU recursive-transaction flush | 2 | 0.00 | 0.00 |
| IMU undo allocation size | 2,963,816 | 91.62 | 6,305.99 |
| IMU- failed to get a private strand | 0 | 0.00 | 0.00 |
| Misses for writing mapping | 1 | 0.00 | 0.00 |
| SMON posted for undo segment recovery | 0 | 0.00 | 0.00 |
| SMON posted for undo segment shrink | 1 | 0.00 | 0.00 |
| SQL*Net roundtrips to/from client | 11,305 | 0.35 | 24.05 |
| SQL*Net roundtrips to/from dblink | 0 | 0.00 | 0.00 |
| active txn count during cleanout | 514 | 0.02 | 1.09 |
| application wait time | 0 | 0.00 | 0.00 |
| background checkpoints completed | 0 | 0.00 | 0.00 |
| background checkpoints started | 0 | 0.00 | 0.00 |
| background timeouts | 103,524 | 3.20 | 220.26 |
| branch node splits | 0 | 0.00 | 0.00 |
| buffer is not pinned count | 681,226 | 21.06 | 1,449.42 |
| buffer is pinned count | 814,014 | 25.16 | 1,731.94 |
| bytes received via SQL*Net from client | 1,857,036 | 57.41 | 3,951.14 |
| bytes received via SQL*Net from dblink | 0 | 0.00 | 0.00 |
| bytes sent via SQL*Net to client | 2,571,367 | 79.49 | 5,470.99 |
| bytes sent via SQL*Net to dblink | 0 | 0.00 | 0.00 |
| calls to get snapshot scn: kcmgss | 193,488 | 5.98 | 411.68 |
| calls to kcmgas | 114,625 | 3.54 | 243.88 |
| calls to kcmgcs | 882 | 0.03 | 1.88 |
| change write time | 1,415 | 0.04 | 3.01 |
| cleanout - number of ktugct calls | 1,052 | 0.03 | 2.24 |
| cleanouts and rollbacks - consistent read gets | 7 | 0.00 | 0.01 |
| cleanouts only - consistent read gets | 337 | 0.01 | 0.72 |
| cluster key scan block gets | 138,309 | 4.28 | 294.27 |
| cluster key scans | 47,091 | 1.46 | 100.19 |
| commit batch performed | 5 | 0.00 | 0.01 |
| commit batch requested | 5 | 0.00 | 0.01 |
| commit batch/immediate performed | 42 | 0.00 | 0.09 |
| commit batch/immediate requested | 42 | 0.00 | 0.09 |
| commit cleanout failures: block lost | 81 | 0.00 | 0.17 |
| commit cleanout failures: buffer being written | 2 | 0.00 | 0.00 |
| commit cleanout failures: callback failure | 118 | 0.00 | 0.25 |
| commit cleanout failures: cannot pin | 0 | 0.00 | 0.00 |
| commit cleanouts | 9,208 | 0.28 | 19.59 |
| commit cleanouts successfully completed | 9,007 | 0.28 | 19.16 |
| commit immediate performed | 37 | 0.00 | 0.08 |
| commit immediate requested | 37 | 0.00 | 0.08 |
| commit txn count during cleanout | 868 | 0.03 | 1.85 |
| concurrency wait time | 1,740 | 0.05 | 3.70 |
| consistent changes | 4,256 | 0.13 | 9.06 |
| consistent gets | 1,331,333 | 41.16 | 2,832.62 |
| consistent gets - examination | 501,375 | 15.50 | 1,066.76 |
| consistent gets direct | 143 | 0.00 | 0.30 |
| consistent gets from cache | 1,331,190 | 41.15 | 2,832.32 |
| current blocks converted for CR | 0 | 0.00 | 0.00 |
| cursor authentications | 138 | 0.00 | 0.29 |
| data blocks consistent reads - undo records applied | 1,488 | 0.05 | 3.17 |
| db block changes | 208,293 | 6.44 | 443.18 |
| db block gets | 222,910 | 6.89 | 474.28 |
| db block gets direct | 1,143 | 0.04 | 2.43 |
| db block gets from cache | 221,767 | 6.86 | 471.84 |
| deferred (CURRENT) block cleanout applications | 4,939 | 0.15 | 10.51 |
| dirty buffers inspected | 2,684 | 0.08 | 5.71 |
| enqueue conversions | 6,533 | 0.20 | 13.90 |
| enqueue releases | 322,528 | 9.97 | 686.23 |
| enqueue requests | 322,528 | 9.97 | 686.23 |
| enqueue timeouts | 0 | 0.00 | 0.00 |
| enqueue waits | 1 | 0.00 | 0.00 |
| exchange deadlocks | 0 | 0.00 | 0.00 |
| execute count | 50,821 | 1.57 | 108.13 |
| failed probes on index block reclamation | 0 | 0.00 | 0.00 |
| free buffer inspected | 210,389 | 6.50 | 447.64 |
| free buffer requested | 248,386 | 7.68 | 528.48 |
| heap block compress | 383 | 0.01 | 0.81 |
| hot buffers moved to head of LRU | 13,904 | 0.43 | 29.58 |
| immediate (CR) block cleanout applications | 344 | 0.01 | 0.73 |
| immediate (CURRENT) block cleanout applications | 2,885 | 0.09 | 6.14 |
| index crx upgrade (found) | 0 | 0.00 | 0.00 |
| index crx upgrade (positioned) | 22,843 | 0.71 | 48.60 |
| index fast full scans (full) | 708 | 0.02 | 1.51 |
| index fetch by key | 302,293 | 9.34 | 643.18 |
| index scans kdiixs1 | 85,109 | 2.63 | 181.08 |
| leaf node 90-10 splits | 128 | 0.00 | 0.27 |
| leaf node splits | 288 | 0.01 | 0.61 |
| lob reads | 1,880 | 0.06 | 4.00 |
| lob writes | 7,809 | 0.24 | 16.61 |
| lob writes unaligned | 7,809 | 0.24 | 16.61 |
| logons cumulative | 205 | 0.01 | 0.44 |
| messages received | 6,191 | 0.19 | 13.17 |
| messages sent | 6,191 | 0.19 | 13.17 |
| no buffer to keep pinned count | 0 | 0.00 | 0.00 |
| no work - consistent read gets | 725,519 | 22.43 | 1,543.66 |
| opened cursors cumulative | 31,189 | 0.96 | 66.36 |
| parse count (failures) | 3 | 0.00 | 0.01 |
| parse count (hard) | 1,148 | 0.04 | 2.44 |
| parse count (total) | 18,664 | 0.58 | 39.71 |
| parse time cpu | 334 | 0.01 | 0.71 |
| parse time elapsed | 3,807 | 0.12 | 8.10 |
| physical read IO requests | 125,864 | 3.89 | 267.80 |
| physical read bytes | 2,002,092,032 | 61,890.12 | 4,259,770.28 |
| physical read total IO requests | 143,144 | 4.42 | 304.56 |
| physical read total bytes | 2,283,642,880 | 70,593.63 | 4,858,814.64 |
| physical read total multi block requests | 10,092 | 0.31 | 21.47 |
| physical reads | 244,396 | 7.55 | 519.99 |
| physical reads cache | 244,253 | 7.55 | 519.69 |
| physical reads cache prefetch | 118,532 | 3.66 | 252.20 |
| physical reads direct | 143 | 0.00 | 0.30 |
| physical reads direct (lob) | 140 | 0.00 | 0.30 |
| physical reads direct temporary tablespace | 0 | 0.00 | 0.00 |
| physical reads prefetch warmup | 0 | 0.00 | 0.00 |
| physical write IO requests | 6,456 | 0.20 | 13.74 |
| physical write bytes | 86,122,496 | 2,662.28 | 183,239.35 |
| physical write total IO requests | 40,564 | 1.25 | 86.31 |
| physical write total bytes | 665,353,728 | 20,567.90 | 1,415,646.23 |
| physical write total multi block requests | 2,749 | 0.08 | 5.85 |
| physical writes | 10,513 | 0.32 | 22.37 |
| physical writes direct | 1,143 | 0.04 | 2.43 |
| physical writes direct (lob) | 5 | 0.00 | 0.01 |
| physical writes direct temporary tablespace | 1,138 | 0.04 | 2.42 |
| physical writes from cache | 9,370 | 0.29 | 19.94 |
| physical writes non checkpoint | 5,972 | 0.18 | 12.71 |
| pinned buffers inspected | 8 | 0.00 | 0.02 |
| prefetch warmup blocks aged out before use | 0 | 0.00 | 0.00 |
| prefetched blocks aged out before use | 15,887 | 0.49 | 33.80 |
| process last non-idle time | 32,318 | 1.00 | 68.76 |
| recursive aborts on index block reclamation | 0 | 0.00 | 0.00 |
| recursive calls | 533,381 | 16.49 | 1,134.85 |
| recursive cpu usage | 6,256 | 0.19 | 13.31 |
| redo blocks written | 74,879 | 2.31 | 159.32 |
| redo buffer allocation retries | 0 | 0.00 | 0.00 |
| redo entries | 107,520 | 3.32 | 228.77 |
| redo log space requests | 0 | 0.00 | 0.00 |
| redo log space wait time | 0 | 0.00 | 0.00 |
| redo ordering marks | 2,118 | 0.07 | 4.51 |
| redo size | 36,324,264 | 1,122.88 | 77,285.67 |
| redo subscn max counts | 4,005 | 0.12 | 8.52 |
| redo synch time | 30 | 0.00 | 0.06 |
| redo synch writes | 50 | 0.00 | 0.11 |
| redo wastage | 472,740 | 14.61 | 1,005.83 |
| redo write time | 726 | 0.02 | 1.54 |
| redo writer latching time | 0 | 0.00 | 0.00 |
| redo writes | 1,737 | 0.05 | 3.70 |
| rollback changes - undo records applied | 63 | 0.00 | 0.13 |
| rollbacks only - consistent read gets | 1,381 | 0.04 | 2.94 |
| rows fetched via callback | 112,489 | 3.48 | 239.34 |
| session connect time | 83,331 | 2.58 | 177.30 |
| session cursor cache hits | 39,544 | 1.22 | 84.14 |
| session logical reads | 1,554,243 | 48.05 | 3,306.90 |
| session pga memory | 30,538,792 | 944.04 | 64,976.15 |
| session pga memory max | 57,539,624 | 1,778.71 | 122,424.73 |
| session uga memory | 90,199,764,200 | 2,788,320.57 | 191,914,391.91 |
| session uga memory max | 152,440,872 | 4,712.36 | 324,342.28 |
| shared hash latch upgrades - no wait | 23,450 | 0.72 | 49.89 |
| shared hash latch upgrades - wait | 0 | 0.00 | 0.00 |
| sorts (disk) | 0 | 0.00 | 0.00 |
| sorts (memory) | 13,821 | 0.43 | 29.41 |
| sorts (rows) | 8,629,066 | 266.75 | 18,359.71 |
| sql area evicted | 608 | 0.02 | 1.29 |
| sql area purged | 39 | 0.00 | 0.08 |
| summed dirty queue length | 2,777 | 0.09 | 5.91 |
| switch current to new buffer | 166 | 0.01 | 0.35 |
| table fetch by rowid | 270,315 | 8.36 | 575.14 |
| table fetch continued row | 208 | 0.01 | 0.44 |
| table scan blocks gotten | 286,941 | 8.87 | 610.51 |
| table scan rows gotten | 10,571,952 | 326.81 | 22,493.51 |
| table scans (long tables) | 19 | 0.00 | 0.04 |
| table scans (short tables) | 7,564 | 0.23 | 16.09 |
| total number of times SMON posted | 28 | 0.00 | 0.06 |
| transaction rollbacks | 42 | 0.00 | 0.09 |
| undo change vector size | 13,595,072 | 420.26 | 28,925.69 |
| user I/O wait time | 26,456 | 0.82 | 56.29 |
| user calls | 13,335 | 0.41 | 28.37 |
| user commits | 459 | 0.01 | 0.98 |
| user rollbacks | 11 | 0.00 | 0.02 |
| workarea executions - multipass | 0 | 0.00 | 0.00 |
| workarea executions - onepass | 0 | 0.00 | 0.00 |
| workarea executions - optimal | 10,022 | 0.31 | 21.32 |
| write clones created in background | 0 | 0.00 | 0.00 |
| write clones created in foreground | 6 | 0.00 | 0.01 |
来自 “ ITPUB博客 ” ,链接:https://blog.itpub.net/25296295/viewspace-690325/ ,如需转载,请注明出处,否则将追究法律责任。