Skip to Main Content

Oracle Database Discussions

Announcement

For appeals, questions and feedback about Oracle Forums, please email oracle-forums-moderators_us@oracle.com. Technical questions should be asked in the appropriate category. Thank you!

High I/O Issue

User_OCZ1TOct 31 2018 — edited Nov 1 2018

Hi, We are using version 11.2.0.4 of oracle Exadata. It is type X5. Our infrastructure team recently enabled "FlashCache writeback mode" on this. And as per the doc it states, Write-back flash cache significantly improves the write intensive operations because writing to flash cache is faster than writing to Hard disks. Writeback is the default starting with X6 Exadata models and recommended by Oracle for X4 and above.

But suddenly after this implementation, we see few of the small queries which used to finish within seconds(.03 seconds/execution) were having increased execution time to ~6 seconds/execution for next  7-8hours and then getting back to normal then after. And these small queries were affecting because of high number of executions, so with increase in time per execution, the overall job execution time was increasing. After digging more into them i found no change in execution path and also the volume is same, it was mainly "cell single block physical read" which has increased for these queries. Then checking the overall USER I/O(cell single block physical read) trend for the database from DBA_HIST_SYSTEM_EVENT, i see ,it(cell single block physical read) has been significantly increased after the change implemented and its getting back to BAU after around 7-8 hrs+. I was not able to relate the symptom with the enabling of "FlashCache writeback mode". Want to know from experts how this can be logically related with each other?

I have few questions ,

1)Is it possible that during the activity for enabling "writeback flash cache" mode flash cache has flushed out completely and its the cache warmup due to which the "cell single block physical read" took longer initially and after ~8hrs they are coming back to BAU? Is it correct to think it can take ~8hrs for cache warmup? Is there some statistics(may be exadata flash cache related) which i can refer to confirm this thought?

2)Is there somewhere in oem i can see the flash cache metrics to find any matching clue?

Below is sample query with its execution plan and details from dba_hist_sqlstat.

INSERT INTO tab1(c1, c2, c3.....c39)  VALUES ( :1, :2, :3, :4, :5, :6, :7,

:8, :9, :10, :11, :12, :13, :14, :15, :16, :17, :18, :19, :20, :21,

:22, :23, :24, :25, :26, :27, :28, :29, :30, :31, :32, :33, :34, :35,

:36, :37, :38, :39)

-------------------------------------------------

| Id  | Operation                | Name | Cost  |

-------------------------------------------------

|   0 | INSERT STATEMENT         |      |     1 |

|   1 |  LOAD TABLE CONVENTIONAL |      |       |

-------------------------------------------------

Note

-----

   - cpu costing is off (consider enabling it)

Below query used to fetch the query specific stats:- After 28th Oct 4pm the query ran slow till 29th Oct 3AM.

SUM (q.EXECUTIONS_DELTA) executions,

         ROUND (SUM (DISK_READS_delta) / GREATEST (SUM (executions_delta), 1),     1)   pio_per_exec,

         ROUND (SUM (BUFFER_GETS_delta) / GREATEST (SUM (executions_delta), 1),   1) lio_per_exec,

         ROUND (SUM (rows_processed_delta) / GREATEST (SUM (executions_delta), 1), 1) rows_per_exec,

             ROUND (SUM (cpu_time_delta) / GREATEST (SUM (executions_delta), 1),  1) cpu_time_exec,

             ROUND (SUM (ccwait_delta) / GREATEST (SUM (executions_delta), 1),  1) concurrency_per_exec,

             ROUND (SUM (IOWAIT_delta) / GREATEST (SUM (executions_delta), 1),   1) IO_per_exec,

                 ROUND (SUM (ApWAIT_delta) / GREATEST (SUM (executions_delta), 1),   1) apwait_per_exec,

         round ((  SUM (ELAPSED_TIME_delta)/ GREATEST (SUM (executions_delta), 1) / 1000000), 4)  msec_exec

    FROM dba_hist_sqlstat q, dba_hist_snapshot s

          

SAMPLE_ENDEXECUTIONSPIO_PER_EXECLIO_PER_EXECROWS_PER_EXECCPU_TIME_EXECCONCURRENCY_PER_EXECIO_PER_EXECAPWAIT_PER_EXECMSEC_EXEC
24-OCT-2018 116723377.9638.71048865.5033630.800.0428
24-OCT-2018 12852772.76241048378.8028958.200.0359
25-OCT-2018 116952877.4641.51048728.2031705.500.0416
25-OCT-2018 12618864.26251047996.4020054.200.0303
26-OCT-2018 117674275.6640.81048581.8028931.700.0373
26-OCT-2018 12413561622.71047515.7017421.700.0238
27-OCT-2018 116807578641.81048726.8031291.500.0416
27-OCT-2018 122628767630.31047710022730.300.0293
28-OCT-2018 16372144.5574.210438980.506343659.81.16.4138
28-OCT-2018 171436106.3579.61041641402477367.502.4957
28-OCT-2018 18196999.6582.410413972.901810094.501.8264
28-OCT-2018 192018102.8580.310414403.701759101.901.7779
28-OCT-2018 203047108.2580.710413779.90115691201.1774
28-OCT-2018 21682298.258010411893.50510079.500.5263
28-OCT-2018 221577990.1581.11049850.40218710.300.2271
28-OCT-2018 232654176.8578.81048463.73.8127027.900.1345
29-OCT-2018 003962100.5580.110412248.80889709.600.8995
29-OCT-2018 01768191.8580.410410477.30459808.300.4684
29-OCT-2018 022303778.3580.81048350.80117437.200.1247
29-OCT-2018 039263870598.91047616.2018209.400.0275
29-OCT-2018 117347679.6564.71047859.2029528.600.0375
30-OCT-2018 117409377.1579.21047926.1022989.500.032
This post has been answered by Andris Perkons-Oracle on Oct 31 2018
Jump to Answer
Comments
Post Details
Added on Oct 31 2018
2 comments
397 views