Error: line 1589: [: =: unary operator expected

[proxy117]

Hello folks.
I am trying the parser on a 11.2.0.3 AWR report and getting error from script.

Here is the version of parser:
AWRSCRIPT_VERSION="1.02"
AWRSCRIPT_LASTUPDATE="05/01/2015"

Please help to get it working.

$ ./awr-parser.sh awrrep_151026_3386819768_1.txt

Filename,Database Name,Instance Number,Instance Name,Database Version,Cluster,Hostname,Host OS,Num CPUs,Server Memory (GB),DB Block Size,Begin Snap,Begin Time,End Snap,End Time,Elapsed Time (mins),DB Time (mins),Average Active Sessions,Busy Flag,Logical Reads/sec,Block Changes/sec,Read IOPS,Write IOPS,Redo IOPS,All Write IOPS,Total IOPS,Read Throughput (MiB/sec),Write Throughput (MiB/sec),Redo Throughput (MiB/sec),All Write Throughput (MiB/sec),Total Throughput (MiB/sec),DB CPU Time (s),DB CPU %DBTime,Wait Class User I/O Waits,Wait Class User I/O Time (s),Wait Class User I/O Latency (ms),Wait Class User I/O %DBTime,User Calls/sec,Parses/sec,Hard Parses/sec,Logons/sec,Executes/sec,Transactions/sec,Buffer Hit Ratio (%),In-Memory Sort Ratio (%),Log Switches (Total),Log Switches (Per Hour),Top5 Event1 Name,Top5 Event1 Class,Top5 Event1 Waits,Top5 Event1 Time (s),Top5 Event1 Average Time (ms),Top5 Event1 %DBTime,Top5 Event2 Name,Top5 Event2 Class,Top5 Event2 Waits,Top5 Event2 Time (s),Top5 Event2 Average Time (ms),Top5 Event2 %DBTime,Top5 Event3 Name,Top5 Event3 Class,Top5 Event3 Waits,Top5 Event3 Time (s),Top5 Event3 Average Time (ms),Top5 Event3 %DBTime,Top5 Event4 Name,Top5 Event4 Class,Top5 Event4 Waits,Top5 Event4 Time (s),Top5 Event4 Average Time (ms),Top5 Event4 %DBTime,Top5 Event5 Name,Top5 Event5 Class,Top5 Event5 Waits,Top5 Event5 Time (s),Top5 Event5 Average Time (ms),Top5 Event5 %DBTime,db file sequential read Waits,db file sequential read Time (s),db file sequential read Latency (ms),db file sequential read %DBTime,db file scattered read Waits,db file scattered read Time (s),db file scattered read Latency (ms),db file scattered read %DBTime,direct path read Waits,direct path read Time (s),direct path read Latency (ms),direct path read %DBTime,direct path write Waits,direct path write Time (s),direct path write Latency (ms),direct path write %DBTime,direct path read temp Waits,direct path read temp Time (s),direct path read temp Latency (ms),direct path read temp %DBTime,direct path write temp Waits,direct path write temp Time (s),direct path write temp Latency (ms),direct path write temp %DBTime,log file sync Waits,log file sync Time (s),log file sync Latency (ms),log file sync %DBTime,db file parallel write Waits,db file parallel write Time (s),db file parallel write Latency (ms),db file parallel write %DBTime,log file parallel write Waits,log file parallel write Time (s),log file parallel write Latency (ms),log file parallel write %DBTime,log file sequential read Waits,log file sequential read Time (s),log file sequential read Latency (ms),log file sequential read %DBTime,OS busy time,OS idle time,OS iowait time,OS sys time,OS user time,OS cpu wait time,OS resource mgr wait time,Data Guard Flag,Exadata Flag,Wait Class Admin Waits,Wait Class Admin Time (s),Wait Class Admin Latency (ms),Wait Class Admin %DBTime,Wait Class Application Waits,Wait Class Application Time (s),Wait Class Application Latency (ms),Wait Class Application %DBTime,Wait Class Cluster Waits,Wait Class Cluster Time (s),Wait Class Cluster Latency (ms),Wait Class Cluster %DBTime,Wait Class Commit Waits,Wait Class Commit Time (s),Wait Class Commit Latency (ms),Wait Class Commit %DBTime,Wait Class Concurrency Waits,Wait Class Concurrency Time (s),Wait Class Concurrency Latency (ms),Wait Class Concurrency %DBTime,Wait Class Configuration Waits,Wait Class Configuration Time (s),Wait Class Configuration Latency (ms),Wait Class Configuration %DBTime,Wait Class Network Waits,Wait Class Network Time (s),Wait Class Network Latency (ms),Wait Class Network %DBTime,Wait Class Other Waits,Wait Class Other Time (s),Wait Class Other Latency (ms),Wait Class Other %DBTime,Wait Class System I/O Waits,Wait Class System I/O Time (s),Wait Class System I/O Latency (ms),Wait Class System I/O %DBTime,Histogram <1ms db file sequential read,Histogram <2ms db file sequential read,Histogram <4ms db file sequential read,Histogram <8ms db file sequential read,Histogram <16ms db file sequential read,Histogram <32ms db file sequential read,Histogram <1s db file sequential read,Histogram >1s db file sequential read,Histogram <1ms log file parallel write,Histogram <2ms log file parallel write,Histogram <4ms log file parallel write,Histogram <8ms log file parallel write,Histogram <16ms log file parallel write,Histogram <32ms log file parallel write,Histogram <1s log file parallel write,Histogram >1s log file parallel write
Info : Parsing file awrrep_151026_3386819768_1.txt at 2015-11-30 16:13:50
syntax error on line 1 stdin
./awr-parser.sh: line 1589: [: =: unary operator expected
awrrep_151026_3386819768_1.txt,NETCLD,1,netcld1,11.2.0.3.0,Y,srbh-serv-netcoo,AIX-Based Systems (64-bit),16,16.00,8K,26656,26-Oct-15 10:00:48,26662,26-Oct-15 16:00:04,359.27,734.75,2.0,N,10309.9,198.0,114.7,4.6,3.1,7.7,122.4,7.05,.14,.06,.20,7.25,1075,2.4,2346477,20300,8.651,46.0,6.3,2.1,0.6,0.2,10.9,0.8,91.65,100.00,5,.84,db file scattered read,User I/O,1167541,12886,11.037,29.2,db file sequential read,User I/O,1167506,6727,5.762,15.3,DB CPU,,,1075,,2.4,direct path read,User I/O,9986,678,67.895,1.5,SQL*Net more data from dblink,Network,32821,266,8.105,.6,1167506,6727,5.762,15.3,1167541,12886,11.037,29.2,9986,678,67.895,1.5,,,,,,,,,1,0,0,.0,8223,9,1.094,.0,37824,49,1.295,6.8,66535,51,0.767,7.0,918,11,11.983,1.6,1481475,33669540,2108,1061193,420282,7489900,0,N,N,2,0,0,0.0,35466,22,0.620,0.1,141112,142,1.006,0.3,8223,9,1.094,0.0,62796,27,0.430,0.1,43,4,93.023,0.0,147773,284,1.922,0.6,43847,103,2.349,0.2,3434,81,23.588,0.2,20.4,1.1,11.2,55.2,9.8,1.2,1.2,.0,93.8,3.3,1.0,.9,.6,.2,.2,
Info : No more files found
Info :
Info : SUMMARY
Info : Files found : 1
Info : Files processed : 1
Info : Processing errors : 0
Info :
Info : Completed successfully

[proxy117]

awrrep_150926_3386819768_1.txt

[flashdba]

The file you uploaded runs perfectly for me without the error. Which version of bash do you have installed?

Please re-run using the hidden -X option (which creates LOTS of debug output), pipe to a file and then send the resulting file. Like this:

./awr-parser.sh -X awrrep_151026_3386819768_1.txt > /dev/null 2> output.log

[proxy117]

Hi there. Thanks for getting back to me.

$ bash --version
GNU bash, version 4.2.50(1)-release (powerpc-ibm-aix6.1.2.0)
Copyright (C) 2011 Free Software Foundation, Inc.
License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html

This is AIX 7.1

On Tue, Dec 1, 2015 at 3:10 PM, flashdba notifications@github.com wrote:

The file you uploaded runs perfectly for me without the error. Which
version of bash do you have installed?

Please re-run using the hidden -X option (which creates LOTS of debug
output), pipe to a file and then send the resulting file. Like this:

./awr-parser.sh -X awrrep_151026_3386819768_1.txt > /dev/null 2> output.log

—
Reply to this email directly or view it on GitHub
#1 (comment).

[flashdba]

I have no way of testing this on AIX but the file you sent me works fine on Linux (RHEL6.4) and on my Mac. You'll need to run the debug option I mentioned to find out what's happening.

[proxy117]

Debug log was attached to my previous email two weeks back.

I also tested the script on Solaris 5.10 with same result.

So here is what I think:

Error:
Info : Start post-processing section|
Debug: Calling BC: echo '0 > 16' | bc -l
syntax error on line 1, teletype
./awr-parser.sh: line 1589: [: =: unary operator expected

Program:
1589 if [ $(echo "$AWR_AAS > $DB_NUM_CPUS" | bc
-l) = 1 ]; then

Problem:
number <> number produces boolean, therefore condition "boolean = 1*" *is a
syntax error

Suggested solution:
1589 if [ $AWR_AAS -gt $DB_NUM_CPUS ]; then

Program completes alright. I am checking the output.
Happy holidays.
:)

On Mon, Dec 14, 2015 at 10:07 AM, flashdba notifications@github.com wrote:

I have no way of testing this on AIX but the file you sent me works fine
on Linux (RHEL6.4) and on my Mac. You'll need to run the debug option I
mentioned to find out what's happening.

—
Reply to this email directly or view it on GitHub
#1 (comment).

[flashdba]

For some reason I never received an attachment to your email.

I like your suggested solution and it makes me wonder why I chose to originally solve the problem the way I did. That's the problem with looking at code that you wrote years ago... :-/

[proxy117]

Hi there,

Here are the attachments again, the ones from original email.

Please tell me, is it difficult to add a few more extractions to the script?
This program extracts a lot of data and that's great, however I need some
data not included currently in the output.
Do you have some sort of guide on how to add another extraction? Maybe I
could do it myself as I am somewhat capable of ksh writing.

Thanks,
Yuri

On Tue, Dec 15, 2015 at 7:44 AM, flashdba notifications@github.com wrote:

For some reason I never received an attachment to your email.

I like your suggested solution and it makes me wonder why I chose to
originally solve the problem the way I did. That's the problem with looking
at code that you wrote years ago... :-/

—
Reply to this email directly or view it on GitHub
#1 (comment).

Debug: Running in debug mode - expect lots of output...
Debug: Script called with parameters: -X awrrep_151026_3386819768_1.txt
Debug: Found basic calculator installed in path /oracle/ggs/ggs:/oracle/netcld/base/product/11.2.0/db_1/bin:/usr/local/bin:/usr/sbin:/usr/bin:/bin:/usr/bin:/etc:/usr/sbin:/usr/ucb:/opt/oracle/bin:/usr/bin/X11:/sbin:.
Debug: Setting IFS to SPACE, TAB and NEWLINE
Info : Printing header row|
Info : |
Info : Parsing file awrrep_151026_3386819768_1.txt at 2015-12-01 13:25:00|
Debug: State change AWR_SECTION: reset to Profile
Debug: Setting IFS to SPACE, TAB and NEWLINE
Debug: Skipping blank line line 1 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 2 Size=4 IFS=WORD = |WORKLOAD REPOSITORY report for|
Debug: Skipping blank line line 3 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 4 Size=11 IFS=WORD = |DB Name DB Id Instance Inst Num Startup Time Release RAC|
Info : Start of Database System details at line 4|
Info : Using AWR Format 11|
Debug: Skipping line 5 due to AWRLINE_SKIP counter (1) = |------------ ----------- ------------ -------- --------------- ----------- ---|
Debug: In AWRProfile line 6 Size=8 IFS=WORD = |NETCLD 3386819768 netcld1 1 04-Aug-15 14:30 11.2.0.3.0 YES|
Debug: Sys Details: DBName=NETCLD, InstName=netcld1, InstNum=1, Ver=11.2.0.3.0, Cluster=Y
Debug: Skipping blank line line 7 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 8 Size=7 IFS=WORD = |Host Name Platform CPUs Cores Sockets Memory(GB)|
Info : Start of Host System details at line 8|
Debug: Setting IFS to NEWLINE
Debug: Skipping line 9 due to AWRLINE_SKIP counter (1) = |---------------- -------------------------------- ---- ----- ------- ----------|
Debug: In AWRProfile line 10 Size=1 IFS=LINE = |srbh-serv-netcoo AIX-Based Systems (64-bit) 16 4 16.00|
Debug: Host Details: Hostname=srbh-serv-netcoo, OS=AIX-Based Systems (64-bit), HostMem=16.00
Debug: Setting IFS to SPACE, TAB and NEWLINE
Debug: Skipping blank line line 11 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 12 Size=6 IFS=WORD = |Snap Id Snap Time Sessions Curs/Sess|
Debug: In AWRProfile line 13 Size=4 IFS=WORD = |--------- ------------------- -------- ---------|
Debug: In AWRProfile line 14 Size=7 IFS=WORD = |Begin Snap: 26656 26-Oct-15 10:00:48 67 1.6|
Debug: AWR_BEGIN_SNAP = |26656|
Debug: AWR_BEGIN_TIME = |26-Oct-15 10:00:48|
Debug: In AWRProfile line 15 Size=7 IFS=WORD = |End Snap: 26662 26-Oct-15 16:00:04 68 1.6|
Debug: AWR_END_SNAP = |26662|
Debug: AWR_END_TIME = |26-Oct-15 16:00:04|
Debug: In AWRProfile line 16 Size=3 IFS=WORD = |Elapsed: 359.27 (mins)|
Debug: AWR_ELAPSED_TIME = |359.27|
Debug: Calling BC: echo 'scale=3; 359.27 * 60' | bc -l
Debug: AWR_ELAPSED_TIME_SECS = |21556.20|
Debug: In AWRProfile line 17 Size=4 IFS=WORD = |DB Time: 734.75 (mins)|
Debug: AWR_DB_TIME = |734.75|
Debug: Calling BC: AWR_AAS=echo 'scale=1; 734.75 / 359.27' | bc -l
Debug: AWR_ELAPSED_TIME = |359.27|
Debug: Skipping blank line line 18 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 19 Size=4 IFS=WORD = |Cache Sizes Begin End|
Debug: In AWRProfile line 20 Size=3 IFS=WORD = |~~~~~~~~~~~ ---------- ----------|
Debug: In AWRProfile line 21 Size=8 IFS=WORD = |Buffer Cache: 3,648M 3,648M Std Block Size: 8K|
Debug: DB_BLOCK_SIZE = |8K|
Debug: In AWRProfile line 22 Size=8 IFS=WORD = |Shared Pool Size: 1,584M 1,584M Log Buffer: 9,504K|
Debug: Skipping blank line line 23 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 24 Size=10 IFS=WORD = |Load Profile Per Second Per Transaction Per Exec Per Call|
Debug: In AWRProfile line 25 Size=5 IFS=WORD = |~~~~~~~~~~~~ --------------- --------------- ---------- ----------|
Debug: In AWRProfile line 26 Size=6 IFS=WORD = |DB Time(s): 2.1 2.5 0.19 0.32|
Debug: In AWRProfile line 27 Size=6 IFS=WORD = |DB CPU(s): 0.1 0.1 0.00 0.01|
Debug: In AWRProfile line 28 Size=4 IFS=WORD = |Redo size: 57,972.1 70,487.0|
Debug: Calling BC: REDO_WRITE_THROUGHPUT=echo 'scale=2; ($(echo 'scale=6; 57972.1 / 1048576' | bc -l)+0.005)/1' | bc -l
Debug: REDO_WRITE_THROUGHPUT = |.06|
Debug: In AWRProfile line 29 Size=4 IFS=WORD = |Logical reads: 10,309.9 12,535.6|
Debug: AWR_LOGICAL_READS = |10309.9|
Debug: In AWRProfile line 30 Size=4 IFS=WORD = |Block changes: 198.0 240.7|
Debug: AWR_BLOCK_CHANGES = |198.0|
Debug: In AWRProfile line 31 Size=4 IFS=WORD = |Physical reads: 886.1 1,077.4|
Debug: In AWRProfile line 32 Size=4 IFS=WORD = |Physical writes: 6.7 8.1|
Debug: In AWRProfile line 33 Size=4 IFS=WORD = |User calls: 6.3 7.7|
Debug: AWR_USER_CALLS = |6.3|
Debug: In AWRProfile line 34 Size=3 IFS=WORD = |Parses: 2.1 2.5|
Debug: AWR_PARSES = |2.1|
Debug: In AWRProfile line 35 Size=4 IFS=WORD = |Hard parses: 0.6 0.7|
Debug: AWR_HARD_PARSES = |0.6|
Debug: In AWRProfile line 36 Size=5 IFS=WORD = |W/A MB processed: 0.3 0.4|
Debug: In AWRProfile line 37 Size=3 IFS=WORD = |Logons: 0.2 0.2|
Debug: AWR_LOGONS = |0.2|
Debug: In AWRProfile line 38 Size=3 IFS=WORD = |Executes: 10.9 13.3|
Debug: AWR_EXECUTES = |10.9|
Debug: In AWRProfile line 39 Size=3 IFS=WORD = |Rollbacks: 0.3 0.4|
Debug: In AWRProfile line 40 Size=2 IFS=WORD = |Transactions: 0.8|
Debug: AWR_TRANSACTIONS = |0.8|
Debug: Skipping blank line line 41 Size=1 IFS=WORD = ||
Debug: In AWRProfile line 42 Size=5 IFS=WORD = |Instance Efficiency Percentages (Target 100%)|
Info : End of AWR Profile section found at line 42|
Debug: State change AWR_SECTION: AWRProfile -> InstanceEfficiency
Debug: Setting IFS to SPACE, TAB and NEWLINE
Debug: Skipping line 43 due to AWRLINE_SKIP counter (1) = |~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~|
Debug: In InstanceEfficiency line 44 Size=8 IFS=WORD = |Buffer Nowait %: 99.99 Redo NoWait %: 100.00|
Debug: In InstanceEfficiency line 45 Size=8 IFS=WORD = |Buffer Hit %: 91.65 In-memory Sort %: 100.00|
Debug: BUFFER_HIT_RATIO = |91.65|
Debug: INMEMORY_SORT_RATIO = |100.00|
Info : End of Instance Efficiency section found at line 45|
Debug: State change AWR_SECTION: InstanceEfficiency -> SearchingForNextSection
Debug: Setting IFS to NEWLINE
Debug: In SearchingForNextSection line 46 Size=1 IFS=LINE = | Library Hit %: 93.28 Soft Parse %: 71.05|
Debug: In SearchingForNextSection line 47 Size=1 IFS=LINE = | Execute to Parse %: 81.04 Latch Hit %: 99.77|
Debug: In SearchingForNextSection line 48 Size=1 IFS=LINE = |Parse CPU to Parse Elapsd %: 32.30 % Non-Parse CPU: 98.20|
Debug: Skipping blank line line 49 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 50 Size=1 IFS=LINE = | Shared Pool Statistics Begin End|
Debug: In SearchingForNextSection line 51 Size=1 IFS=LINE = | ------ ------|
Debug: In SearchingForNextSection line 52 Size=1 IFS=LINE = | Memory Usage %: 44.19 44.24|
Debug: In SearchingForNextSection line 53 Size=1 IFS=LINE = | % SQL with executions>1: 80.01 80.72|
Debug: In SearchingForNextSection line 54 Size=1 IFS=LINE = | % Memory for SQL w/exec>1: 71.65 72.31|
Debug: Skipping blank line line 55 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 56 Size=1 IFS=LINE = |Top 5 Timed Foreground Events|
Info : Start of 11g Top 5 section found at line 56|
Debug: State change AWR_SECTION: SearchingForNextSection -> Top5Foreground
Debug: Setting IFS to NEWLINE
Debug: Skipping line 57 due to AWRLINE_SKIP counter (4) = |~~~~~~~~~~~~~~~~~~~~~~~~~~~~~|
Debug: Skipping line 58 due to AWRLINE_SKIP counter (3) = | Avg|
Debug: Skipping line 59 due to AWRLINE_SKIP counter (2) = | wait % DB|
Debug: Skipping line 60 due to AWRLINE_SKIP counter (1) = |Event Waits Time(s) (ms) time Wait Class|
Debug: In Top5Foreground line 61 Size=1 IFS=LINE = |------------------------------ ------------ ----------- ------ ------ ----------|
Debug: Searching for header row... found
Debug: Entering process_header_row() with argument ------------------------------ ------------ ----------- ------ ------ ----------
Debug: Analyzing header row with 6 columns
Debug: Computed column widths: AWRCOL1=1-30, AWRCOL2=32-43, AWRCOL3=45-55, AWRCOL4=57-62, AWRCOL5=64-69, AWRCOL6=71-80, AWRCOL7=, AWRCOL3=, AWRCOL4=, AWRCOL5=
Debug: In Top5Foreground line 62 Size=1 IFS=LINE = |db file scattered read 1,167,541 12,886 11 29.2 User I/O|
Debug: Calling BC: TOP5EVENT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (12886 / 1167541) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Top 5 Line 1: Name=db file scattered read, Waits=1167541, Time=12886, Ave=11.037, PctDBTime=29.2, Class=User I/O
Debug: In Top5Foreground line 63 Size=1 IFS=LINE = |db file sequential read 1,167,506 6,727 6 15.3 User I/O|
Debug: Calling BC: TOP5EVENT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (6727 / 1167506) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Top 5 Line 2: Name=db file sequential read, Waits=1167506, Time=6727, Ave=5.762, PctDBTime=15.3, Class=User I/O
Debug: In Top5Foreground line 64 Size=1 IFS=LINE = |DB CPU 1,075 2.4|
Debug: Top 5 line 3 is DB CPU
Debug: Top 5 Line 3: Name=DB CPU, Waits=, Time=1075, Ave=, PctDBTime=2.4, Class=
Debug: In Top5Foreground line 65 Size=1 IFS=LINE = |direct path read 9,986 678 68 1.5 User I/O|
Debug: Calling BC: TOP5EVENT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (678 / 9986) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Top 5 Line 4: Name=direct path read, Waits=9986, Time=678, Ave=67.895, PctDBTime=1.5, Class=User I/O
Debug: In Top5Foreground line 66 Size=1 IFS=LINE = |SQL_Net more data from dblink 32,821 266 8 .6 Network|
Debug: Calling BC: TOP5EVENT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (266 / 32821) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Top 5 Line 5: Name=SQL_Net more data from dblink, Waits=32821, Time=266, Ave=8.105, PctDBTime=.6, Class=Network
Debug: In Top5Foreground line 67 Size=1 IFS=LINE = |Host CPU (CPUs: 16 Cores: 4 Sockets: )|
Info : End of Top 5 section found at line 67|
Debug: State change AWR_SECTION: Top5Foreground -> SearchingForNextSection
Debug: Setting IFS to NEWLINE
Debug: In SearchingForNextSection line 68 Size=1 IFS=LINE = |~~~~~~~~ Load Average|
Debug: In SearchingForNextSection line 69 Size=1 IFS=LINE = | Begin End %User %System %WIO %Idle|
Debug: In SearchingForNextSection line 70 Size=1 IFS=LINE = | --------- --------- --------- --------- --------- ---------|
Debug: In SearchingForNextSection line 71 Size=1 IFS=LINE = | 2.68 5.12 1.2 3.0 0.0 95.8|
Debug: Skipping blank line line 72 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 73 Size=1 IFS=LINE = |Instance CPU|
Debug: In SearchingForNextSection line 74 Size=1 IFS=LINE = |~~~~~~~~~~~~|
Debug: In SearchingForNextSection line 75 Size=1 IFS=LINE = | % of total CPU for Instance: 0.4|
Debug: In SearchingForNextSection line 76 Size=1 IFS=LINE = | % of busy CPU for Instance: 9.3|
Debug: In SearchingForNextSection line 77 Size=1 IFS=LINE = | %DB time waiting for CPU - Resource Mgr: 0.0|
Debug: Skipping blank line line 78 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 79 Size=1 IFS=LINE = |Memory Statistics|
Debug: In SearchingForNextSection line 80 Size=1 IFS=LINE = |~~~~~~~~~~~~~~~~~ Begin End|
Debug: In SearchingForNextSection line 81 Size=1 IFS=LINE = | Host Mem (MB): 16,384.0 16,384.0|
Debug: In SearchingForNextSection line 82 Size=1 IFS=LINE = | SGA use (MB): 5,328.0 5,328.0|
Debug: In SearchingForNextSection line 83 Size=1 IFS=LINE = | PGA use (MB): 461.2 460.9|
Debug: In SearchingForNextSection line 84 Size=1 IFS=LINE = | % Host Mem used for SGA+PGA: 35.33 35.33|
Debug: Skipping blank line line 85 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 86 Size=1 IFS=LINE = |RAC Statistics DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Debug: Skipping blank line line 87 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 88 Size=1 IFS=LINE = | Begin End|
Debug: In SearchingForNextSection line 89 Size=1 IFS=LINE = | ----- -----|
Debug: In SearchingForNextSection line 90 Size=1 IFS=LINE = | Number of Instances: 2 2|
Debug: Skipping blank line line 91 Size=1 IFS=LINE = ||
Debug: Skipping blank line line 92 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 93 Size=1 IFS=LINE = |Global Cache Load Profile|
Debug: In SearchingForNextSection line 94 Size=1 IFS=LINE = |~~~~~~~~~~~~~~~~~~~~~~~~~ Per Second Per Transaction|
Debug: In SearchingForNextSection line 95 Size=1 IFS=LINE = | --------------- ---------------|
Debug: In SearchingForNextSection line 96 Size=1 IFS=LINE = | Global Cache blocks received: 16.91 20.56|
Debug: In SearchingForNextSection line 97 Size=1 IFS=LINE = | Global Cache blocks served: 18.92 23.00|
Debug: In SearchingForNextSection line 98 Size=1 IFS=LINE = | GCS/GES messages received: 84.58 102.84|
Debug: In SearchingForNextSection line 99 Size=1 IFS=LINE = | GCS/GES messages sent: 75.64 91.97|
Debug: In SearchingForNextSection line 100 Size=1 IFS=LINE = | DBWR Fusion writes: 1.15 1.40|
Debug: In SearchingForNextSection line 101 Size=1 IFS=LINE = | Estd Interconnect traffic (KB) 317.88|
Debug: Skipping blank line line 102 Size=1 IFS=LINE = ||
Debug: Skipping blank line line 103 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 104 Size=1 IFS=LINE = |Global Cache Efficiency Percentages (Target local+remote 100%)|
Debug: In SearchingForNextSection line 105 Size=1 IFS=LINE = |~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~|
Debug: In SearchingForNextSection line 106 Size=1 IFS=LINE = |Buffer access - local cache %: 91.46|
Debug: In SearchingForNextSection line 107 Size=1 IFS=LINE = |Buffer access - remote cache %: 0.19|
Debug: In SearchingForNextSection line 108 Size=1 IFS=LINE = |Buffer access - disk %: 8.35|
Debug: Skipping blank line line 109 Size=1 IFS=LINE = ||
Debug: Skipping blank line line 110 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 111 Size=1 IFS=LINE = |Global Cache and Enqueue Services - Workload Characteristics|
Debug: In SearchingForNextSection line 112 Size=1 IFS=LINE = |~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~|
Debug: In SearchingForNextSection line 113 Size=1 IFS=LINE = | Avg global enqueue get time (ms): 0.1|
Debug: Skipping blank line line 114 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 115 Size=1 IFS=LINE = | Avg global cache cr block receive time (ms): 0.8|
Debug: In SearchingForNextSection line 116 Size=1 IFS=LINE = | Avg global cache current block receive time (ms): 1.0|
Debug: Skipping blank line line 117 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 118 Size=1 IFS=LINE = | Avg global cache cr block build time (ms): 0.0|
Debug: In SearchingForNextSection line 119 Size=1 IFS=LINE = | Avg global cache cr block send time (ms): 0.0|
Debug: In SearchingForNextSection line 120 Size=1 IFS=LINE = | Global cache log flushes for cr blocks served %: 31.1|
Debug: In SearchingForNextSection line 121 Size=1 IFS=LINE = | Avg global cache cr block flush time (ms): 2.4|
Debug: Skipping blank line line 122 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 123 Size=1 IFS=LINE = | Avg global cache current block pin time (ms): 0.2|
Debug: In SearchingForNextSection line 124 Size=1 IFS=LINE = | Avg global cache current block send time (ms): 0.0|
Debug: In SearchingForNextSection line 125 Size=1 IFS=LINE = | Global cache log flushes for current blocks served %: 8.6|
Debug: In SearchingForNextSection line 126 Size=1 IFS=LINE = | Avg global cache current block flush time (ms): 1.0|
Debug: Skipping blank line line 127 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 128 Size=1 IFS=LINE = |Global Cache and Enqueue Services - Messaging Statistics|
Debug: In SearchingForNextSection line 129 Size=1 IFS=LINE = |~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~|
Debug: In SearchingForNextSection line 130 Size=1 IFS=LINE = | Avg message sent queue time (ms): 0.0|
Debug: In SearchingForNextSection line 131 Size=1 IFS=LINE = | Avg message sent queue time on ksxp (ms): 2.6|
Debug: In SearchingForNextSection line 132 Size=1 IFS=LINE = | Avg message received queue time (ms): 0.1|
Debug: In SearchingForNextSection line 133 Size=1 IFS=LINE = | Avg GCS message process time (ms): 0.0|
Debug: In SearchingForNextSection line 134 Size=1 IFS=LINE = | Avg GES message process time (ms): 0.0|
Debug: Skipping blank line line 135 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 136 Size=1 IFS=LINE = | % of direct sent messages: 33.42|
Debug: In SearchingForNextSection line 137 Size=1 IFS=LINE = | % of indirect sent messages: 66.53|
Debug: In SearchingForNextSection line 138 Size=1 IFS=LINE = | % of flow controlled messages: 0.05|
Debug: In SearchingForNextSection line 139 Size=1 IFS=LINE = | -------------------------------------------------------------|
Debug: Skipping blank line line 140 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 141 Size=1 IFS=LINE = |Cluster Interconnect|
Debug: In SearchingForNextSection line 142 Size=1 IFS=LINE = |-> if IP/Public/Source at End snap is different a '*' is displayed|
Debug: In SearchingForNextSection line 143 Size=1 IFS=LINE = |~~~~~~~~~~~~~~~~~~~~|
Debug: In SearchingForNextSection line 144 Size=1 IFS=LINE = | Begin End|
Debug: In SearchingForNextSection line 145 Size=1 IFS=LINE = | -------------------------------------------------- -----------|
Debug: In SearchingForNextSection line 146 Size=1 IFS=LINE = |Interface IP Address Pub Source IP Pub Src|
Debug: In SearchingForNextSection line 147 Size=1 IFS=LINE = |---------- --------------- --- ------------------------------ --- --- ---|
Debug: In SearchingForNextSection line 148 Size=1 IFS=LINE = |en3 169.254.170.236 N|
Debug: In SearchingForNextSection line 149 Size=1 IFS=LINE = |Time Model Statistics DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Info : Start of Time Model Statistics section found at line 149|
Info : Ignoring Time Model Statistics because AWR_FORMAT 11 not 10|
Debug: Skipping line 150 due to AWRSECTION_SKIP counter (1) = |-> Total time in database user-calls (DB Time): 44085.3s|
Debug: Skipping line 151 due to AWRSECTION_SKIP counter (1) = |-> Statistics including the word "background" measure background process|
Debug: Skipping line 152 due to AWRSECTION_SKIP counter (1) = | time, and so do not contribute to the DB time statistic|
Debug: Skipping line 153 due to AWRSECTION_SKIP counter (1) = |-> Ordered by % or DB time desc, Statistic name|
Debug: Skipping blank line line 154 Size=1 IFS=LINE = ||
Debug: Skipping line 155 due to AWRSECTION_SKIP counter (1) = |Statistic Name Time (s) % of DB Time|
Debug: Skipping line 156 due to AWRSECTION_SKIP counter (1) = |------------------------------------------ ------------------ ------------|
Debug: Skipping line 157 due to AWRSECTION_SKIP counter (1) = |sql execute elapsed time 23,035.2 52.3|
Debug: Skipping line 158 due to AWRSECTION_SKIP counter (1) = |DB CPU 1,074.8 2.4|
Debug: Skipping line 159 due to AWRSECTION_SKIP counter (1) = |parse time elapsed 61.6 .1|
Debug: Skipping line 160 due to AWRSECTION_SKIP counter (1) = |hard parse elapsed time 48.7 .1|
Debug: Skipping line 161 due to AWRSECTION_SKIP counter (1) = |failed parse elapsed time 37.4 .1|
Debug: Skipping line 162 due to AWRSECTION_SKIP counter (1) = |sequence load elapsed time 26.1 .1|
Debug: Skipping line 163 due to AWRSECTION_SKIP counter (1) = |PL/SQL execution elapsed time 12.8 .0|
Debug: Skipping line 164 due to AWRSECTION_SKIP counter (1) = |connection management call elapsed time 4.3 .0|
Debug: Skipping line 165 due to AWRSECTION_SKIP counter (1) = |hard parse (sharing criteria) elapsed time 3.5 .0|
Debug: Skipping line 166 due to AWRSECTION_SKIP counter (1) = |PL/SQL compilation elapsed time 0.1 .0|
Debug: Skipping line 167 due to AWRSECTION_SKIP counter (1) = |repeated bind elapsed time 0.1 .0|
Debug: Skipping line 168 due to AWRSECTION_SKIP counter (1) = |hard parse (bind mismatch) elapsed time 0.0 .0|
Debug: Skipping line 169 due to AWRSECTION_SKIP counter (1) = |DB time 44,085.3|
Debug: Skipping line 170 due to AWRSECTION_SKIP counter (1) = |background elapsed time 729.2|
Debug: Skipping line 171 due to AWRSECTION_SKIP counter (1) = |background cpu time 307.3|
Debug: In SearchingForNextSection line 172 Size=1 IFS=LINE = | -------------------------------------------------------------|
Debug: Found AWR section terminator at 172 - decrementing AWRSECTION_SKIP counter to 0
Debug: Skipping blank line line 173 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 174 Size=1 IFS=LINE = |Operating System Statistics DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Info : Start of Operating System Stats section found at line 174|
Debug: State change AWR_SECTION: SearchingForNextSection -> OperatingSystemStats
Debug: Setting IFS to SPACE, TAB and NEWLINE
Debug: Skipping line 175 due to AWRLINE_SKIP counter (5) = |-> _TIME statistic values are diffed.|
Debug: Skipping line 176 due to AWRLINE_SKIP counter (4) = |All others display actual values. End Value is displayed if different|
Debug: Skipping line 177 due to AWRLINE_SKIP counter (3) = |-> ordered by statistic type (CPU Use, Virtual Memory, Hardware Config), Name|
Debug: Skipping blank line line 178 Size=1 IFS=WORD = ||
Debug: Skipping line 179 due to AWRLINE_SKIP counter (2) = |Statistic Value End Value|
Debug: Skipping line 180 due to AWRLINE_SKIP counter (1) = |------------------------- ---------------------- ----------------|
Debug: In OperatingSystemStats line 181 Size=2 IFS=WORD = |AVG_BUSY_TIME 91,571|
Debug: In OperatingSystemStats line 182 Size=2 IFS=WORD = |AVG_IDLE_TIME 2,103,321|
Debug: In OperatingSystemStats line 183 Size=2 IFS=WORD = |AVG_IOWAIT_TIME 47|
Debug: In OperatingSystemStats line 184 Size=2 IFS=WORD = |AVG_SYS_TIME 65,306|
Debug: In OperatingSystemStats line 185 Size=2 IFS=WORD = |AVG_USER_TIME 25,262|
Debug: In OperatingSystemStats line 186 Size=2 IFS=WORD = |BUSY_TIME 1,481,475|
Debug: OS_BUSY_TIME = |1481475|
Debug: In OperatingSystemStats line 187 Size=2 IFS=WORD = |IDLE_TIME 33,669,540|
Debug: OS_IDLE_TIME = |33669540|
Debug: In OperatingSystemStats line 188 Size=2 IFS=WORD = |IOWAIT_TIME 2,108|
Debug: OS_IOWAIT_TIME = |2108|
Debug: In OperatingSystemStats line 189 Size=2 IFS=WORD = |SYS_TIME 1,061,193|
Debug: OS_SYS_TIME = |1061193|
Debug: In OperatingSystemStats line 190 Size=2 IFS=WORD = |USER_TIME 420,282|
Debug: OS_USER_TIME = |420282|
Debug: In OperatingSystemStats line 191 Size=3 IFS=WORD = |LOAD 3 5|
Debug: In OperatingSystemStats line 192 Size=2 IFS=WORD = |OS_CPU_WAIT_TIME 7,489,900|
Debug: OS_CPU_WAIT_TIME = |7489900|
Debug: In OperatingSystemStats line 193 Size=2 IFS=WORD = |RSRC_MGR_CPU_WAIT_TIME 0|
Debug: OS_RSRC_MGR_WAIT_TIME = |0|
Debug: In OperatingSystemStats line 194 Size=2 IFS=WORD = |VM_IN_BYTES 69,259,264|
Debug: In OperatingSystemStats line 195 Size=2 IFS=WORD = |VM_OUT_BYTES 257,875,968|
Debug: In OperatingSystemStats line 196 Size=2 IFS=WORD = |PHYSICAL_MEMORY_BYTES 17,179,869,184|
Debug: In OperatingSystemStats line 197 Size=2 IFS=WORD = |NUM_CPUS 16|
Debug: DB_NUM_CPUS = |16|
Debug: In OperatingSystemStats line 198 Size=2 IFS=WORD = |NUM_CPU_CORES 4|
Debug: In OperatingSystemStats line 199 Size=2 IFS=WORD = |NUM_LCPUS 16|
Debug: In OperatingSystemStats line 200 Size=2 IFS=WORD = |NUM_VCPUS 4|
Debug: In OperatingSystemStats line 201 Size=2 IFS=WORD = |GLOBAL_RECEIVE_SIZE_MAX 4,194,304|
Debug: In OperatingSystemStats line 202 Size=2 IFS=WORD = |GLOBAL_SEND_SIZE_MAX 4,194,304|
Debug: In OperatingSystemStats line 203 Size=2 IFS=WORD = |TCP_RECEIVE_SIZE_DEFAULT 16,384|
Debug: In OperatingSystemStats line 204 Size=2 IFS=WORD = |TCP_RECEIVE_SIZE_MAX 9.2233720368547758E+18|
Debug: In OperatingSystemStats line 205 Size=2 IFS=WORD = |TCP_RECEIVE_SIZE_MIN 4,096|
Debug: In OperatingSystemStats line 206 Size=2 IFS=WORD = |TCP_SEND_SIZE_DEFAULT 16,384|
Debug: In OperatingSystemStats line 207 Size=2 IFS=WORD = |TCP_SEND_SIZE_MAX 9.2233720368547758E+18|
Debug: In OperatingSystemStats line 208 Size=2 IFS=WORD = |TCP_SEND_SIZE_MIN 4,096|
Debug: In OperatingSystemStats line 209 Size=1 IFS=WORD = |-------------------------------------------------------------|
Info : Found AWR section terminator at 209 - marking end of section OperatingSystemStats|
Debug: State change AWR_SECTION: OperatingSystemStats -> SearchingForNextSection
Debug: Setting IFS to NEWLINE
Debug: Skipping blank line line 210 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 211 Size=1 IFS=LINE = |Operating System Statistics - DetailDB/Inst: NETCLD/netcld1 Snaps: 26656-2666|
Debug: Skipping blank line line 212 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 213 Size=1 IFS=LINE = |Snap Time Load %busy %user %sys %idle %iowait|
Debug: In SearchingForNextSection line 214 Size=1 IFS=LINE = |--------------- -------- -------- -------- -------- -------- --------|
Debug: In SearchingForNextSection line 215 Size=1 IFS=LINE = |26-Oct 10:00:48 2.7 N/A N/A N/A N/A N/A|
Debug: In SearchingForNextSection line 216 Size=1 IFS=LINE = |26-Oct 11:00:07 1.7 4.2 1.1 3.0 95.8 0.0|
Debug: In SearchingForNextSection line 217 Size=1 IFS=LINE = |26-Oct 12:00:13 0.9 4.2 1.2 3.0 95.8 0.0|
Debug: In SearchingForNextSection line 218 Size=1 IFS=LINE = |26-Oct 13:00:01 2.6 4.1 1.1 3.0 95.9 0.0|
Debug: In SearchingForNextSection line 219 Size=1 IFS=LINE = |26-Oct 14:00:07 9.5 4.3 1.3 3.0 95.7 0.0|
Debug: In SearchingForNextSection line 220 Size=1 IFS=LINE = |26-Oct 15:00:13 4.9 4.3 1.2 3.1 95.7 0.0|
Debug: In SearchingForNextSection line 221 Size=1 IFS=LINE = |26-Oct 16:00:04 5.1 4.2 1.2 3.0 95.8 0.0|
Debug: In SearchingForNextSection line 222 Size=1 IFS=LINE = | -------------------------------------------------------------|
Debug: Skipping blank line line 223 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 224 Size=1 IFS=LINE = |Foreground Wait Class DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Info : Start of Foreground Wait Class section found at line 224|
Debug: State change AWR_SECTION: SearchingForNextSection -> ForegroundWaitClass
Debug: Setting IFS to NEWLINE
Debug: Skipping line 225 due to AWRLINE_SKIP counter (7) = |-> s - second, ms - millisecond - 1000th of a second|
Debug: Skipping line 226 due to AWRLINE_SKIP counter (6) = |-> ordered by wait time desc, waits desc|
Debug: Skipping line 227 due to AWRLINE_SKIP counter (5) = |-> %Timeouts: value of 0 indicates value was < .5%. Value of null is truly 0|
Debug: Skipping line 228 due to AWRLINE_SKIP counter (4) = |-> Captured Time accounts for 50.0% of Total DB time 44,085.27 (s)|
Debug: Skipping line 229 due to AWRLINE_SKIP counter (3) = |-> Total FG Wait Time: 20,972.93 (s) DB CPU time: 1,074.78 (s)|
Debug: Skipping blank line line 230 Size=1 IFS=LINE = ||
Debug: Skipping line 231 due to AWRLINE_SKIP counter (2) = | Avg|
Debug: Skipping line 232 due to AWRLINE_SKIP counter (1) = | %Time Total Wait wait|
Debug: In ForegroundWaitClass line 233 Size=1 IFS=LINE = |Wait Class Waits -outs Time (s) (ms) %DB time|
Debug: Still in section header - ignore
Debug: In ForegroundWaitClass line 234 Size=1 IFS=LINE = |-------------------- ---------------- ----- ---------------- -------- ---------|
Debug: Entering process_header_row() with argument -------------------- ---------------- ----- ---------------- -------- ---------
Debug: Analyzing header row with 6 columns
Debug: Computed column widths: AWRCOL1=1-20, AWRCOL2=22-37, AWRCOL3=39-43, AWRCOL4=45-60, AWRCOL5=62-69, AWRCOL6=71-79, AWRCOL7=, AWRCOL3=, AWRCOL4=, AWRCOL5=
Debug: In ForegroundWaitClass line 235 Size=1 IFS=LINE = |User I/O 2,346,477 0 20,300 9 46.0|
Info : Processing Foreground Wait Class User I/O|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (20300 / 2346477) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class USRIO: Waits=2346477, Time=20300, PctDBTime=46.0, Ave=8.651
Debug: In ForegroundWaitClass line 236 Size=1 IFS=LINE = |DB CPU 1,075 2.4|
Info : Processing Foreground Wait Class DB CPU|
Debug: AWR_DB_CPU = |1075|
Debug: AWR_DB_CPU_PCT_DBTIME = |2.4|
Debug: In ForegroundWaitClass line 237 Size=1 IFS=LINE = |Network 147,773 0 284 2 0.6|
Info : Processing Foreground Wait Class Network|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (284 / 147773) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class NETWK: Waits=147773, Time=284, PctDBTime=0.6, Ave=1.922
Debug: In ForegroundWaitClass line 238 Size=1 IFS=LINE = |Cluster 141,112 0 142 1 0.3|
Info : Processing Foreground Wait Class Cluster|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (142 / 141112) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class CLSTR: Waits=141112, Time=142, PctDBTime=0.3, Ave=1.006
Debug: In ForegroundWaitClass line 239 Size=1 IFS=LINE = |Other 43,847 53 103 2 0.2|
Info : Processing Foreground Wait Class Other|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (103 / 43847) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class OTHER: Waits=43847, Time=103, PctDBTime=0.2, Ave=2.349
Debug: In ForegroundWaitClass line 240 Size=1 IFS=LINE = |System I/O 3,434 0 81 24 0.2|
Info : Processing Foreground Wait Class System I/O|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (81 / 3434) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class SYSIO: Waits=3434, Time=81, PctDBTime=0.2, Ave=23.588
Debug: In ForegroundWaitClass line 241 Size=1 IFS=LINE = |Concurrency 62,796 1 27 0 0.1|
Info : Processing Foreground Wait Class Concurrency|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (27 / 62796) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class CNCUR: Waits=62796, Time=27, PctDBTime=0.1, Ave=0.430
Debug: In ForegroundWaitClass line 242 Size=1 IFS=LINE = |Application 35,466 0 22 1 0.1|
Info : Processing Foreground Wait Class Application|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (22 / 35466) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class APPLN: Waits=35466, Time=22, PctDBTime=0.1, Ave=0.620
Debug: In ForegroundWaitClass line 243 Size=1 IFS=LINE = |Commit 8,223 0 9 1 0.0|
Info : Processing Foreground Wait Class Commit|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (9 / 8223) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class COMMT: Waits=8223, Time=9, PctDBTime=0.0, Ave=1.094
Debug: In ForegroundWaitClass line 244 Size=1 IFS=LINE = |Configuration 43 14 4 96 0.0|
Info : Processing Foreground Wait Class Configuration|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (4 / 43) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class CONFG: Waits=43, Time=4, PctDBTime=0.0, Ave=93.023
Debug: In ForegroundWaitClass line 245 Size=1 IFS=LINE = |Administrative 2 100 0 100 0.0|
Info : Processing Foreground Wait Class Administrative|
Debug: Calling BC: WAIT_CLASS_AVEWAIT=echo 'scale=3; ($(echo 'scale=7; (0 / 2) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Class ADMIN: Waits=2, Time=0, PctDBTime=0.0, Ave=0
Debug: In ForegroundWaitClass line 246 Size=1 IFS=LINE = | -------------------------------------------------------------|
Info : Found AWR section terminator at 246 - marking end of section ForegroundWaitClass|
Debug: State change AWR_SECTION: ForegroundWaitClass -> SearchingForNextSection
Debug: Setting IFS to NEWLINE
Debug: Skipping blank line line 247 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 248 Size=1 IFS=LINE = |Foreground Wait Events DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Info : Start of Foreground Wait Events section found at line 248|
Debug: State change AWR_SECTION: SearchingForNextSection -> ForegroundWaitEvents
Debug: Setting IFS to NEWLINE
Debug: Skipping line 249 due to AWRLINE_SKIP counter (3) = |-> s - second, ms - millisecond - 1000th of a second|
Debug: Skipping line 250 due to AWRLINE_SKIP counter (2) = |-> Only events with Total Wait Time (s) >= .001 are shown|
Debug: Skipping line 251 due to AWRLINE_SKIP counter (1) = |-> ordered by wait time desc, waits desc (idle events last)|
Debug: In ForegroundWaitEvents line 252 Size=1 IFS=LINE = |-> %Timeouts: value of 0 indicates value was < .5%. Value of null is truly 0|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 1)
Debug: Skipping blank line line 253 Size=1 IFS=LINE = ||
Debug: In ForegroundWaitEvents line 254 Size=1 IFS=LINE = | Avg|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 2)
Debug: In ForegroundWaitEvents line 255 Size=1 IFS=LINE = | %Time Total Wait wait Waits % DB|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 3)
Debug: In ForegroundWaitEvents line 256 Size=1 IFS=LINE = |Event Waits -outs Time (s) (ms) /txn time|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 4)
Debug: In ForegroundWaitEvents line 257 Size=1 IFS=LINE = |-------------------------- ------------ ----- ---------- ------- -------- ------|
Debug: Searching for header row... found
Debug: Entering process_header_row() with argument -------------------------- ------------ ----- ---------- ------- -------- ------
Debug: Analyzing header row with 7 columns
Debug: Computed column widths: AWRCOL1=1-26, AWRCOL2=28-39, AWRCOL3=41-45, AWRCOL4=47-56, AWRCOL5=58-64, AWRCOL6=66-73, AWRCOL7=75-80, AWRCOL3=, AWRCOL4=, AWRCOL5=
Debug: In ForegroundWaitEvents line 258 Size=1 IFS=LINE = |db file scattered read 1,167,541 0 12,886 11 65.9 29.2|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (12886 / 1167541) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Events DFXR: Waits=1167541, Time=12886, PctDBTime=29.2, Ave=11.037
Debug: In ForegroundWaitEvents line 259 Size=1 IFS=LINE = |db file sequential read 1,167,506 0 6,727 6 65.9 15.3|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (6727 / 1167506) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Events DFSR: Waits=1167506, Time=6727, PctDBTime=15.3, Ave=5.762
Debug: In ForegroundWaitEvents line 260 Size=1 IFS=LINE = |direct path read 9,986 0 678 68 0.6 1.5|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (678 / 9986) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Events DPRD: Waits=9986, Time=678, PctDBTime=1.5, Ave=67.895
Debug: In ForegroundWaitEvents line 261 Size=1 IFS=LINE = |SQL_Net more data from dbl 32,821 0 266 8 1.9 .6|
Debug: In ForegroundWaitEvents line 262 Size=1 IFS=LINE = |PX Deq: Slave Session Stat 1,822 0 87 48 0.1 .2|
Debug: In ForegroundWaitEvents line 263 Size=1 IFS=LINE = |control file sequential re 3,434 0 81 24 0.2 .2|
Debug: In ForegroundWaitEvents line 264 Size=1 IFS=LINE = |gc buffer busy acquire 11,295 0 36 3 0.6 .1|
Debug: In ForegroundWaitEvents line 265 Size=1 IFS=LINE = |gc cr block 2-way 41,310 0 31 1 2.3 .1|
Debug: In ForegroundWaitEvents line 266 Size=1 IFS=LINE = |SQL_Net break/reset to cli 35,282 0 22 1 2.0 .1|
Debug: In ForegroundWaitEvents line 267 Size=1 IFS=LINE = |gc current block 2-way 37,048 0 21 1 2.1 .0|
Debug: In ForegroundWaitEvents line 268 Size=1 IFS=LINE = |gc cr multi block request 11,822 0 18 2 0.7 .0|
Debug: In ForegroundWaitEvents line 269 Size=1 IFS=LINE = |SQL_Net message from dblin 260 0 16 61 0.0 .0|
Debug: In ForegroundWaitEvents line 270 Size=1 IFS=LINE = |gc current grant busy 24,843 0 15 1 1.4 .0|
Debug: In ForegroundWaitEvents line 271 Size=1 IFS=LINE = |row cache lock 5,503 0 14 2 0.3 .0|
Debug: In ForegroundWaitEvents line 272 Size=1 IFS=LINE = |gc current block busy 5,760 0 10 2 0.3 .0|
Debug: In ForegroundWaitEvents line 273 Size=1 IFS=LINE = |log file sync 8,223 0 9 1 0.5 .0|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (9 / 8223) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Events LFSY: Waits=8223, Time=9, PctDBTime=.0, Ave=1.094
Debug: In ForegroundWaitEvents line 274 Size=1 IFS=LINE = |db file parallel read 390 0 8 21 0.0 .0|
Debug: In ForegroundWaitEvents line 275 Size=1 IFS=LINE = |gc cr block busy 5,146 0 8 1 0.3 .0|
Debug: In ForegroundWaitEvents line 276 Size=1 IFS=LINE = |DFS lock handle 8,044 10 6 1 0.5 .0|
Debug: In ForegroundWaitEvents line 277 Size=1 IFS=LINE = |log buffer space 36 0 4 111 0.0 .0|
Debug: In ForegroundWaitEvents line 278 Size=1 IFS=LINE = |IPC send completion sync 7,163 0 3 0 0.4 .0|
Debug: In ForegroundWaitEvents line 279 Size=1 IFS=LINE = |enq: TX - index contention 313 0 3 10 0.0 .0|
Debug: In ForegroundWaitEvents line 280 Size=1 IFS=LINE = |latch: cache buffers chain 49,541 0 3 0 2.8 .0|
Debug: In ForegroundWaitEvents line 281 Size=1 IFS=LINE = |cursor: pin S wait on X 648 0 3 5 0.0 .0|
Debug: In ForegroundWaitEvents line 282 Size=1 IFS=LINE = |name-service call wait 36 0 3 75 0.0 .0|
Debug: In ForegroundWaitEvents line 283 Size=1 IFS=LINE = |gc buffer busy release 1,134 0 3 2 0.1 .0|
Debug: In ForegroundWaitEvents line 284 Size=1 IFS=LINE = |library cache lock 1,126 74 2 2 0.1 .0|
Debug: In ForegroundWaitEvents line 285 Size=1 IFS=LINE = |gc current grant 2-way 2,351 0 1 1 0.1 .0|
Debug: In ForegroundWaitEvents line 286 Size=1 IFS=LINE = |library cache pin 1,345 0 1 1 0.1 .0|
Debug: In ForegroundWaitEvents line 287 Size=1 IFS=LINE = |enq: PS - contention 1,801 1 1 1 0.1 .0|
Debug: In ForegroundWaitEvents line 288 Size=1 IFS=LINE = |SQL_Net more data from cli 8,484 0 1 0 0.5 .0|
Debug: In ForegroundWaitEvents line 289 Size=1 IFS=LINE = |SQL_Net more data to clien 8,221 0 1 0 0.5 .0|
Debug: In ForegroundWaitEvents line 290 Size=1 IFS=LINE = |Disk file operations I/O 1,036 0 1 1 0.1 .0|
Debug: In ForegroundWaitEvents line 291 Size=1 IFS=LINE = |ADR block file write 30 0 1 19 0.0 .0|
Debug: In ForegroundWaitEvents line 292 Size=1 IFS=LINE = |kksfbc child completion 12 83 1 43 0.0 .0|
Debug: In ForegroundWaitEvents line 293 Size=1 IFS=LINE = |buffer busy waits 3,832 0 0 0 0.2 .0|
Debug: In ForegroundWaitEvents line 294 Size=1 IFS=LINE = |enq: JS - job run lock - s 792 100 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 295 Size=1 IFS=LINE = |library cache: mutex X 452 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 296 Size=1 IFS=LINE = |gc current retry 117 0 0 3 0.0 .0|
Debug: In ForegroundWaitEvents line 297 Size=1 IFS=LINE = |SQL_Net message to client 97,726 0 0 0 5.5 .0|
Debug: In ForegroundWaitEvents line 298 Size=1 IFS=LINE = |PX Deq: reap credit 21,446 100 0 0 1.2 .0|
Debug: In ForegroundWaitEvents line 299 Size=1 IFS=LINE = |gc cr failure 16 0 0 13 0.0 .0|
Debug: In ForegroundWaitEvents line 300 Size=1 IFS=LINE = |JS kgl get object wait 2 100 0 100 0.0 .0|
Debug: In ForegroundWaitEvents line 301 Size=1 IFS=LINE = |reliable message 66 0 0 2 0.0 .0|
Debug: In ForegroundWaitEvents line 302 Size=1 IFS=LINE = |KJC: Wait for msg sends to 1,503 0 0 0 0.1 .0|
Debug: In ForegroundWaitEvents line 303 Size=1 IFS=LINE = |undo segment extension 6 100 0 15 0.0 .0|
Debug: In ForegroundWaitEvents line 304 Size=1 IFS=LINE = |gc current split 60 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 305 Size=1 IFS=LINE = |latch: undo global data 143 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 306 Size=1 IFS=LINE = |enq: KO - fast object chec 142 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 307 Size=1 IFS=LINE = |enq: SR - contention 106 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 308 Size=1 IFS=LINE = |log file switch completion 1 0 0 44 0.0 .0|
Debug: In ForegroundWaitEvents line 309 Size=1 IFS=LINE = |CSS initialization 1 0 0 42 0.0 .0|
Debug: In ForegroundWaitEvents line 310 Size=1 IFS=LINE = |latch: ges resource hash l 154 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 311 Size=1 IFS=LINE = |enq: UL - contention 40 85 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 312 Size=1 IFS=LINE = |enq: JQ - contention 25 100 0 2 0.0 .0|
Debug: In ForegroundWaitEvents line 313 Size=1 IFS=LINE = |gc cr grant 2-way 118 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 314 Size=1 IFS=LINE = |cursor: pin S 7 0 0 5 0.0 .0|
Debug: In ForegroundWaitEvents line 315 Size=1 IFS=LINE = |enq: FB - contention 61 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 316 Size=1 IFS=LINE = |read by other session 15 0 0 2 0.0 .0|
Debug: In ForegroundWaitEvents line 317 Size=1 IFS=LINE = |gc current multi block req 47 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 318 Size=1 IFS=LINE = |Foreground Wait Events DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Debug: In ForegroundWaitEvents line 319 Size=1 IFS=LINE = |-> s - second, ms - millisecond - 1000th of a second|
Debug: In ForegroundWaitEvents line 320 Size=1 IFS=LINE = |-> Only events with Total Wait Time (s) >= .001 are shown|
Debug: In ForegroundWaitEvents line 321 Size=1 IFS=LINE = |-> ordered by wait time desc, waits desc (idle events last)|
Debug: In ForegroundWaitEvents line 322 Size=1 IFS=LINE = |-> %Timeouts: value of 0 indicates value was < .5%. Value of null is truly 0|
Debug: Skipping blank line line 323 Size=1 IFS=LINE = ||
Debug: In ForegroundWaitEvents line 324 Size=1 IFS=LINE = | Avg|
Debug: In ForegroundWaitEvents line 325 Size=1 IFS=LINE = | %Time Total Wait wait Waits % DB|
Debug: In ForegroundWaitEvents line 326 Size=1 IFS=LINE = |Event Waits -outs Time (s) (ms) /txn time|
Debug: In ForegroundWaitEvents line 327 Size=1 IFS=LINE = |-------------------------- ------------ ----- ---------- ------- -------- ------|
Debug: In ForegroundWaitEvents line 328 Size=1 IFS=LINE = |direct path write temp 1 0 0 26 0.0 .0|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (0 / 1) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Foreground Wait Events DPWT: Waits=1, Time=0, PctDBTime=.0, Ave=0
Debug: In ForegroundWaitEvents line 329 Size=1 IFS=LINE = |buffer deadlock 121 100 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 330 Size=1 IFS=LINE = |latch: cache buffers lru c 12 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 331 Size=1 IFS=LINE = |latch: row cache objects 19 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 332 Size=1 IFS=LINE = |gc cr block congested 10 0 0 2 0.0 .0|
Debug: In ForegroundWaitEvents line 333 Size=1 IFS=LINE = |latch: gcs resource hash 11 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 334 Size=1 IFS=LINE = |wait list latch free 8 0 0 2 0.0 .0|
Debug: In ForegroundWaitEvents line 335 Size=1 IFS=LINE = |latch: gc element 56 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 336 Size=1 IFS=LINE = |gc cr disk read 30 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 337 Size=1 IFS=LINE = |cursor: mutex S 1 0 0 10 0.0 .0|
Debug: In ForegroundWaitEvents line 338 Size=1 IFS=LINE = |ADR file lock 36 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 339 Size=1 IFS=LINE = |latch: shared pool 9 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 340 Size=1 IFS=LINE = |rdbms ipc reply 31 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 341 Size=1 IFS=LINE = |latch: object queue header 16 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 342 Size=1 IFS=LINE = |enq: JI - contention 8 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 343 Size=1 IFS=LINE = |PX Deq: Signal ACK RSG 4 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 344 Size=1 IFS=LINE = |latch: call allocation 2 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 345 Size=1 IFS=LINE = |gc current block congested 5 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 346 Size=1 IFS=LINE = |ADR block file read 228 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 347 Size=1 IFS=LINE = |CSS operation: action 1 0 0 1 0.0 .0|
Debug: In ForegroundWaitEvents line 348 Size=1 IFS=LINE = |enq: AE - lock 5 0 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 349 Size=1 IFS=LINE = |lock escalate retry 43 100 0 0 0.0 .0|
Debug: In ForegroundWaitEvents line 350 Size=1 IFS=LINE = |SQL_Net message from clien 97,727 0 282,469 2890 5.5|
Debug: In ForegroundWaitEvents line 351 Size=1 IFS=LINE = |jobq slave wait 77,188 98 38,151 494 4.4|
Debug: In ForegroundWaitEvents line 352 Size=1 IFS=LINE = |PL/SQL lock timer 359 100 21,541 60002 0.0|
Debug: In ForegroundWaitEvents line 353 Size=1 IFS=LINE = |PX Deq: Execution Msg 7,272 0 589 81 0.4|
Debug: In ForegroundWaitEvents line 354 Size=1 IFS=LINE = |PX Deq Credit: send blkd 1,439 0 408 284 0.1|
Debug: In ForegroundWaitEvents line 355 Size=1 IFS=LINE = |single-task message 5 0 2 340 0.0|
Debug: In ForegroundWaitEvents line 356 Size=1 IFS=LINE = |PX Deq: Parse Reply 4 0 1 303 0.0|
Debug: In ForegroundWaitEvents line 357 Size=1 IFS=LINE = |PX Deq: Join ACK 4 0 0 1 0.0|
Debug: In ForegroundWaitEvents line 358 Size=1 IFS=LINE = | -------------------------------------------------------------|
Info : Found AWR section terminator at 358 - marking end of section ForegroundWaitEvents|
Debug: State change AWR_SECTION: ForegroundWaitEvents -> SearchingForNextSection
Debug: Setting IFS to NEWLINE
Debug: Skipping blank line line 359 Size=1 IFS=LINE = ||
Debug: In SearchingForNextSection line 360 Size=1 IFS=LINE = |Background Wait Events DB/Inst: NETCLD/netcld1 Snaps: 26656-26662|
Info : Start of Background Wait Events section found at line 360|
Debug: State change AWR_SECTION: SearchingForNextSection -> BackgroundWaitEvents
Debug: Setting IFS to NEWLINE
Debug: Skipping line 361 due to AWRLINE_SKIP counter (3) = |-> ordered by wait time desc, waits desc (idle events last)|
Debug: Skipping line 362 due to AWRLINE_SKIP counter (2) = |-> Only events with Total Wait Time (s) >= .001 are shown|
Debug: Skipping line 363 due to AWRLINE_SKIP counter (1) = |-> %Timeouts: value of 0 indicates value was < .5%. Value of null is truly 0|
Debug: Skipping blank line line 364 Size=1 IFS=LINE = ||
Debug: In BackgroundWaitEvents line 365 Size=1 IFS=LINE = | Avg|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 1)
Debug: In BackgroundWaitEvents line 366 Size=1 IFS=LINE = | %Time Total Wait wait Waits % bg|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 2)
Debug: In BackgroundWaitEvents line 367 Size=1 IFS=LINE = |Event Waits -outs Time (s) (ms) /txn time|
Debug: Searching for header row... not found (BAILOUT_COUNTER = 3)
Debug: In BackgroundWaitEvents line 368 Size=1 IFS=LINE = |-------------------------- ------------ ----- ---------- ------- -------- ------|
Debug: Searching for header row... found
Debug: Entering process_header_row() with argument -------------------------- ------------ ----- ---------- ------- -------- ------
Debug: Analyzing header row with 7 columns
Debug: Computed column widths: AWRCOL1=1-26, AWRCOL2=28-39, AWRCOL3=41-45, AWRCOL4=47-56, AWRCOL5=58-64, AWRCOL6=66-73, AWRCOL7=75-80, AWRCOL3=, AWRCOL4=, AWRCOL5=
Debug: In BackgroundWaitEvents line 369 Size=1 IFS=LINE = |Streams AQ: qmn coordinato 16 100 87 5444 0.0 11.9|
Debug: In BackgroundWaitEvents line 370 Size=1 IFS=LINE = |os thread startup 602 0 74 123 0.0 10.1|
Debug: In BackgroundWaitEvents line 371 Size=1 IFS=LINE = |log file parallel write 66,535 0 51 1 3.8 7.0|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (51 / 66535) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Background Wait Events LFPW: Waits=66535, Time=51, PctDBTime=7.0, Ave=0.767
Debug: In BackgroundWaitEvents line 372 Size=1 IFS=LINE = |db file parallel write 37,824 0 49 1 2.1 6.8|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (49 / 37824) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Background Wait Events DFPW: Waits=37824, Time=49, PctDBTime=6.8, Ave=1.295
Debug: In BackgroundWaitEvents line 373 Size=1 IFS=LINE = |control file sequential re 93,485 0 42 0 5.3 5.7|
Debug: In BackgroundWaitEvents line 374 Size=1 IFS=LINE = |reliable message 2,829 0 14 5 0.2 1.9|
Debug: In BackgroundWaitEvents line 375 Size=1 IFS=LINE = |gcs log flush sync 22,728 1 14 1 1.3 1.9|
Debug: In BackgroundWaitEvents line 376 Size=1 IFS=LINE = |control file parallel writ 23,597 0 13 1 1.3 1.8|
Debug: In BackgroundWaitEvents line 377 Size=1 IFS=LINE = |log file sequential read 918 0 11 12 0.1 1.6|
Debug: Calling BC: WAIT_AVERAGE=echo 'scale=3; ($(echo 'scale=7; (11 / 918) * 1000' | bc -l)+0.0005)/1' | bc -l
Debug: Background Wait Events LFSR: Waits=918, Time=11, PctDBTime=1.6, Ave=11.983
Debug: In BackgroundWaitEvents line 378 Size=1 IFS=LINE = |db file sequential read 445 0 11 25 0.0 1.5|
Debug: In BackgroundWaitEvents line 379 Size=1 IFS=LINE = |db file async I/O submit 36,414 0 3 0 2.1 .4|
Debug: In BackgroundWaitEvents line 380 Size=1 IFS=LINE = |DFS lock handle 4,095 18 2 1 0.2 .3|
Debug: In BackgroundWaitEvents line 381 Size=1 IFS=LINE = |CGS wait for IPC msg 193,417 100 2 0 10.9 .3|
Debug: In BackgroundWaitEvents line 382 Size=1 IFS=LINE = |name-service call wait 19 0 1 58 0.0 .2|
Debug: In BackgroundWaitEvents line 383 Size=1 IFS=LINE = |enq: WF - contention 98 0 1 11 0.0 .2|
Debug: In BackgroundWaitEvents line 384 Size=1 IFS=LINE = |gc cr block 2-way 2,946 0 1 0 0.2 .1|
Debug: In BackgroundWaitEvents line 385 Size=1 IFS=LINE = |gc current block 2-way 2,195 0 1 0 0.1 .1|
Debug: In BackgroundWaitEvents line 386 Size=1 IFS=LINE = |libcache interrupt action 90,181 0 1 0 5.1 .1|
Debug: In BackgroundWaitEvents line 387 Size=1 IFS=LINE = |row cache lock 956 0 1 1 0.1 .1|
Debug: In BackgroundWaitEvents line 388 Size=1 IFS=LINE = |ksxr poll remote instances 74,584 100 1 0 4.2 .1|
Debug: In BackgroundWaitEvents line 389 Size=1 IFS=LINE = |row cache process 148,631 0 1 0 8.4 .1|
Debug: In BackgroundWaitEvents line 390 Size=1 IFS=LINE = |enq: JS - queue lock 33 0 1 18 0.0 .1|
Debug: In BackgroundWaitEvents line 391 Size=1 IFS=LINE = |latch: ges resource hash l 3,792 0 1 0 0.2 .1|
Debug: In BackgroundWaitEvents line 392 Size=1 IFS=LINE = |CSS group membership query 358 0 0 1 0.0 .1|
Debug: In BackgroundWaitEvents line 393 Size=1 IFS=LINE = |CSS operation: data query 358 0 0 1 0.0 .1|
Debug: In BackgroundWaitEvents line 394 Size=1 IFS=LINE = |enq: TM - contention 44 91 0 8 0.0 .0|
Debug: In BackgroundWaitEvents line 395 Size=1 IFS=LINE = |Disk file operations I/O 1,100 0 0 0 0.1 .0|
Debug: In BackgroundWaitEvents line 396 Size=1 IFS=LINE = |gc

[flashdba]

It really depends on the data you wish to extract and its specific location within the AWR report.

The script works on the basis of using "handlers" to process distinct areas of a report. So for example there is a handler in place for the Foreground Wait Class section. If you wanted to extract additional information from this section it would be relatively trivial. Note that there are two types of handler, those where each line of the AWR report is read into an array of words ($IFS_DELIMITER = WORD) and those where it is read as one line ($IFS_DELIMITER = LINE).

However, if you wish to read a new section entirely you will need to write a new handler and add it, along with an entry point in the section commented "Searching For Next Section". Be aware that AWR report formats change significantly through versions 10 to 12.1

[gsaray101]

I'm also seeing lots of these errors:

Debug: Skipping line 4240 due to AWRSECTION_SKIP counter (1) = |DB Locks .01 .01 |
Debug: Skipping line 4241 due to AWRSECTION_SKIP counter (1) = |DB Streams .00 .00 |
Debug: Skipping line 4242 due to AWRSECTION_SKIP counter (1) = |Other .00 .00|

there are actually more, above is just a very small subset of the errors.

[flashdba]

Those aren't errors, it is supposed to be skipping those lines...

On Friday, 12 February 2016, gsaray101 notifications@github.com wrote:

I'm also seeing lots of these errors:

Debug: Skipping line 4240 due to AWRSECTION_SKIP counter (1) = |DB Locks
.01 .01 |
Debug: Skipping line 4241 due to AWRSECTION_SKIP counter (1) = |DB Streams
.00 .00 |
Debug: Skipping line 4242 due to AWRSECTION_SKIP counter (1) = |Other .00
.00|

—
Reply to this email directly or view it on GitHub
#1 (comment).

-- flashdba
http://flashdba.com