Caso 2: sessão dominada por espera de I/O. Relatório de exemplo, gerado pelo WaitScope a partir de um trecho anonimizado de um trace real. Ver o caso 1 Voltar para a página do WaitScope

WaitScope

caso-2.trc

Analisado em 29/10/2026 10:19:52 · 65,3 MiB · 753.357 linhas · até 10 comandos por lista

299276 chamadas recursivas sem chamada de nível 0 correspondente (trace iniciado ou cortado no meio de uma chamada)
Tempo total
1.029,051 s
17 min 9 s
Período
07/03/2016 04:19:11
até 04:36:20
Chamadas ao banco
305.623
180 de nível 0
Esperas
341.608
75,177 s entre chamadas
Comandos distintos
185
65 commits · 0 rollbacks

Sessão e ambiente

Banco
Oracle Database 11g Release 11.2.0.4.0 - 64bit Production
Instância
INST_01
Servidor
MAQ_01 · AIX · MAQ_02
Sessão
128.85179 · pid 29352362
Serviço
SVC_01
Módulo
MOD_01
Ação
Unidade de tempo
microssegundos
Arquivo no servidor
caso2.trc

Diagnóstico

Padrões que costumam explicar o que o trace mostra, em ordem de severidade. São pistas para a análise, não conclusões.

média Muitas leituras de bloco único (db file sequential read) 35,6% do tempo total

323.268 leituras somam 35,6% do tempo total. Antes de acelerar o disco, veja se o plano lê blocos demais (índice pouco seletivo, acesso linha a linha). Ver distribuição de latência

média Comando #7: a operação 1 do plano concentra 98,1% do tempo 10,0% do tempo total

Operação DELETE TAB_0049. Gravou 66.842 blocos no TEMP: a workarea da PGA não bastou. Também pesam: operação 2, INDEX RANGE SCAN IDX_0034 (82,2% das leituras lógicas). As operações estão destacadas no plano, no detalhe do comando. Ver no plano do comando #7

média Comando #1 executado 44.591 vezes 0,8% do tempo total

SQL recursivo executado uma vez por linha, típico de laço em PL/SQL. Processamento em conjunto (um único SQL ou FORALL) costuma reduzir muito o tempo. Ver comando #1

média Comando #2 executado 44.590 vezes 0,5% do tempo total

SQL recursivo executado uma vez por linha, típico de laço em PL/SQL. Processamento em conjunto (um único SQL ou FORALL) costuma reduzir muito o tempo. Ver comando #2

Perfil do tempo de resposta

Cada linha é uma parte do tempo total; somadas, dão 100%. Clique no nome de um componente ou de uma classe para ver o que esse tempo significa, quando ele é normal, quando indica problema e onde conferir no relatório. Tempo não contabilizado é o que o trace não explica por CPU nem por espera: dentro das chamadas, costuma ser fila de CPU ou código sem instrumentação; o valor negativo vem da granularidade da medida de CPU.

ComponenteDuração (s)% do totalgráficoOcorrênciasMédia (s)Mín (s)Máx (s)
User I/O 365,865870 35,6 323.268 0,001132 0,000127 0,314918
cpu 170,263521 16,5 102.658 0,001659 0,000002 104,205928
não contabilizado 149,483409 14,5 305.623 0,000489
não contabilizado 87,677960 8,5
User I/O 82,615851 8,0 5.357 0,015422 0,000560 0,335030
Idle 74,696038 7,3 68 1,098471 0,001397 22,601830
User I/O 60,291100 5,9 8.825 0,006832 0,000008 0,091651
cpu 17,535369 1,7 99.915 0,000176 0,000000 15,886799
User I/O 12,718013 1,2 1.019 0,012481 0,002717 0,073023
User I/O 7,638662 0,7 2.950 0,002589 0,000007 0,102338
cpu 0,110972 0,0 394 0,000282 0,000002 0,019293
cpu 0,054101 0,0 102.656 0,000001 0,000000 0,000028
Configuration 0,052671 0,0 2 0,026336 0,021904 0,030767
Configuration 0,033119 0,0 2 0,016560 0,008202 0,024917
Não classificado 0,008608 0,0 1 0,008608 0,008608 0,008608
Application 0,002383 0,0 3 0,000794 0,000141 0,001668
Não classificado 0,001118 0,0 1 0,001118 0,001118 0,001118
User I/O 0,000993 0,0 36 0,000028 0,000002 0,000065
Não classificado 0,000409 0,0 6 0,000068 0,000000 0,000395
Other 0,000356 0,0 2 0,000178 0,000176 0,000180
Network 0,000159 0,0 68 0,000002 0,000000 0,000006
Total1.029,050682100,0

Por classe de espera

Os mesmos componentes agrupados pela classe de espera do Oracle (wait_class), com CPU e tempo não contabilizado à parte. A soma também é o tempo total.

ClasseDuração (s)% do totalgráficoOcorrênciasPrincipais componentes
529,130489 51,4 341.455 db file sequential read · direct path read · direct path read temp · db file scattered read · direct path write temp
237,161369 23,0 tempo não contabilizado dentro das chamadas · tempo não contabilizado entre chamadas
187,963963 18,3 305.623 CPU: EXEC dbcalls · CPU: FETCH dbcalls · CPU: PARSE dbcalls · CPU: CLOSE dbcalls
74,696038 7,3 68 SQL*Net message from client
0,085790 0,0 4 log file switch completion · log file switch (private strand flush incomplete)
0,010135 0,0 8 CSS initialization · CSS operation: action · CSS operation: query
0,002383 0,0 3 enq: RO - fast object reuse
0,000356 0,0 2 reliable message
0,000159 0,0 68 SQL*Net message to client

Principais comandos por componente e por classe

Quanto cada comando gastou em cada componente do perfil e em cada classe de espera, pelo perfil exclusivo do comando (sem o SQL recursivo que ele dispara). Até 10 comandos por lista: mude no topo da página.

Por componente do perfil

db file sequential read espera365,866 s · 35,6% do tempo total · 10 de 16 comandos

Mais 6 comandos gastaram nele; aumente o número no topo da página para ver.

CPU: EXEC dbcalls cpu170,264 s · 16,5% do tempo total · 10 de 168 comandos

Mais 158 comandos gastaram nele; aumente o número no topo da página para ver.

tempo não contabilizado dentro das chamadas não contabilizado149,483 s · 14,5% do tempo total · 10 de 170 comandos

Mais 160 comandos gastaram nele; aumente o número no topo da página para ver.

direct path read espera82,616 s · 8,0% do tempo total · 1 comando
direct path read temp espera60,291 s · 5,9% do tempo total · 2 comandos
CPU: FETCH dbcalls cpu17,535 s · 1,7% do tempo total · 10 de 108 comandos
#Tempo (s)% do totalgráficoSQL IDTexto
516,72145495,4d7sadnr6zu6k9WITH TAB_0082 AS (SELECT DISTINCT COL_0265.COL_0266 , COL_0265.COL_0032 , COL_0265.COL_0039 , COL_0265.COL_0267 , COL_0265.COL_0268 , COL_0265.COL_0269 , COL_02…
20,3332431,9gfhr7c2b942m8SELECT *** FROM TAB_0074 WHERE COL_0064 = COL_0075.ROT_0019() AND COL_0412 = :B1 AND COL_0032 = COL_0089.COL_0090() AND COL_0039 = COL_0089.ROT_0020() AND ROWNU…
10,2845261,64yrf1rq97m3vySELECT *** FROM TAB_0072 WHERE COL_0064 = COL_0075.ROT_0019() AND COL_0205 = :B2 AND COL_0206 = :B1 AND COL_0032 = COL_0089.COL_0090() AND COL_0039 = COL_0089.R…
30,1588090,9c1q763kjacnm1SELECT X49.COL_0402, X49.COL_0403, SUM(X49.COL_0226) COL_0226, SUM(X49.COL_0227) COL_0227, X49.COL_0404, X49.COL_0405, X49.COL_0406, X49.COL_0407 FROM TAB_0093 …
190,0053900,0d5h8zj9sz0qrgSELECT A.COL_0107, C.COL_0108, A.COL_0109, A.COL_0110, B.COL_0111, B.COL_0112, B.COL_0113, B.COL_0114, B.ROT_0008, B.COL_0115, B.COL_0116, B.COL_0117, B.COL_011…
140,0048790,0aggvkb3vxk338SELECT A.COL_0120, A.COL_0121, A.COL_0113, B.COL_0125, C.COL_0126, C.COL_0127, C.COL_0128, A.COL_0129, A.COL_0130, B.COL_0131, ROWNUM FROM TAB_0032 A, TAB_0033 …
270,0032590,0drt86kmzq1dbhSELECT * FROM TAB_0023 T WHERE ((T.COL_0088 = *** AND :B1 = '***') OR (T.COL_0088 = *** AND T.COL_0032 = COL_0089.COL_0090 AND :B1 = '***') OR (T.COL_0088 = ***…
100,0029960,070uypyj7247nzSELECT COL_0401 FROM TAB_0092 WHERE COL_0204 = :B5 AND COL_0032 = :B4 AND COL_0039 = :B3 AND COL_0398 = :B2 AND COL_0235 = :B1
150,0026970,0g6km04yyxuhtwSELECT NVL(C.COL_0116,***), NVL(COL_0112, '***') FROM TAB_0032 C WHERE C.COL_0120 = :B3 AND C.COL_0121 = :B2 AND C.COL_0113 = :B1
410,0022740,008ndunzrd46fnSELECT DISTINCT COL_0095 FROM TAB_0023 COL_0098 WHERE COL_0098.COL_0032 = COL_0089.COL_0090 AND :B1 = '***' UNION ALL SELECT DISTINCT COL_0095 FROM TAB_0023 COL…

Mais 98 comandos gastaram nele; aumente o número no topo da página para ver.

db file scattered read espera12,718 s · 1,2% do tempo total · 1 comando
direct path write temp espera7,639 s · 0,7% do tempo total · 3 comandos
CPU: PARSE dbcalls cpu0,111 s · 0,0% do tempo total · 10 de 169 comandos
#Tempo (s)% do totalgráficoSQL IDTexto
160,01938817,5fdnsdx5rvuzbrSELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_0032 = a.COL_0032 and b.COL_0039 = nvl(t…
50,01119310,1d7sadnr6zu6k9WITH TAB_0082 AS (SELECT DISTINCT COL_0265.COL_0266 , COL_0265.COL_0032 , COL_0265.COL_0039 , COL_0265.COL_0267 , COL_0265.COL_0268 , COL_0265.COL_0269 , COL_02…
320,0055535,0dhxd3kb4f5ybdSELECT COL_0086, COL_0132, COL_0133, DECODE(COL_0134, '***', '***', '***', '***', '***') COL_0135, COL_0136, COL_0137 FROM TAB_0035 WHERE COL_0032 = COL_0089.CO…
330,0050344,54gh2v4tbkq9a5SELECT '***' FROM TAB_0017 COL_0260, TAB_0016 COL_0241 WHERE COL_0260.COL_0032 = COL_0089.COL_0090 AND COL_0260.COL_0261 = COL_0089.COL_0138 AND COL_0241.COL_02…
170,0031532,87mp3qb0atz09bSELECT *** COL_0211, LPAD(COL_0212, ***, '***') COL_0213, COL_0214, COL_0215, COL_0216, COL_0217.COL_0210, COL_0217.COL_0218, COL_0219.COL_0207 COL_0220, COL_02…
530,0026752,4d7kdjgkfuxcpnDECLARE COL_0074 INTEGER; ROT_0010 COL_0075.COL_0076; BEGIN FOR c1 IN (SELECT * FROM TAB_0021 WHERE COL_0064 = *** AND UPPER( ROT_0008 ) = '***' ORDER BY COL_00…
510,0024102,287x10zx4cp3y0SELECT *** COL_0086 FROM TAB_0024 COL_0087 WHERE COL_0087.COL_0088 = '***' AND COL_0087.COL_0032 = '***' GROUP BY COL_0087.COL_0088 UNION SELECT *** COL_0086 FR…
540,0023692,12pta3vm59y7taSELECT PKG_0005.ROT_0006(), PKG_0005.ROT_0007(), PKG_0005.ROT_0008() FROM dual
340,0023642,13nvfb7mqmg15mSelect PKG_0003.ROT_0003 ( '***' , TO_DATE('***','***') , '***' , '***' ) From Dual
470,0023612,170690x7jfswszSELECT *** COL_0086 FROM TAB_0023 COL_0087 WHERE COL_0087.COL_0088 = '***' AND COL_0087.COL_0032 = '***' GROUP BY COL_0087.COL_0088 UNION SELECT *** COL_0086 FR…

Mais 159 comandos gastaram nele; aumente o número no topo da página para ver.

CPU: CLOSE dbcalls cpu0,054 s · 0,0% do tempo total · 10 de 142 comandos
#Tempo (s)% do totalgráficoSQL IDTexto
10,02512946,44yrf1rq97m3vySELECT *** FROM TAB_0072 WHERE COL_0064 = COL_0075.ROT_0019() AND COL_0205 = :B2 AND COL_0206 = :B1 AND COL_0032 = COL_0089.COL_0090() AND COL_0039 = COL_0089.R…
20,02013637,2gfhr7c2b942m8SELECT *** FROM TAB_0074 WHERE COL_0064 = COL_0075.ROT_0019() AND COL_0412 = :B1 AND COL_0032 = COL_0089.COL_0090() AND COL_0039 = COL_0089.ROT_0020() AND ROWNU…
30,0050099,3c1q763kjacnm1SELECT X49.COL_0402, X49.COL_0403, SUM(X49.COL_0226) COL_0226, SUM(X49.COL_0227) COL_0227, X49.COL_0404, X49.COL_0405, X49.COL_0406, X49.COL_0407 FROM TAB_0093 …
80,0014642,7a4tzy6psxru35update seq$ set increment$=:2,minvalue=:3,maxvalue=:4,cycle#=:5,order$=:6,cache=:7,highwater=:8,audit$=:9,flags=:10 where obj#=:1
100,0004260,870uypyj7247nzSELECT COL_0401 FROM TAB_0092 WHERE COL_0204 = :B5 AND COL_0032 = :B4 AND COL_0039 = :B3 AND COL_0398 = :B2 AND COL_0235 = :B1
140,0002640,5aggvkb3vxk338SELECT A.COL_0120, A.COL_0121, A.COL_0113, B.COL_0125, C.COL_0126, C.COL_0127, C.COL_0128, A.COL_0129, A.COL_0130, B.COL_0131, ROWNUM FROM TAB_0032 A, TAB_0033 …
150,0002270,4g6km04yyxuhtwSELECT NVL(C.COL_0116,***), NVL(COL_0112, '***') FROM TAB_0032 C WHERE C.COL_0120 = :B3 AND C.COL_0121 = :B2 AND C.COL_0113 = :B1
290,0001360,361x5n662780a6select u.name, o.name, a.COL_0052, o.obj# from association$ a, user$ u, obj$ o where a.obj# = :1 and a.property = :2 and a.COL_0053 = o.obj# and u.user# = o.own…
1260,0001060,2gyas258427y28BEGIN ROT_0017(COL_0257, COL_0258, COL_0259); END;
520,0000910,20fxxq8umxumv3select count(COL_0251) from SYS_FBA_TRACKEDTABLES where OBJ# = :1 and bitand(FLAGS, :2)=***

Mais 132 comandos gastaram nele; aumente o número no topo da página para ver.

Por classe de espera

User I/O529,130 s · 51,4% do tempo total · 10 de 17 comandos

Mais 7 comandos gastaram nele; aumente o número no topo da página para ver.

Não contabilizado237,161 s · 23,0% do tempo total · 10 de 170 comandos

Mais 160 comandos gastaram nele; aumente o número no topo da página para ver.

CPU187,964 s · 18,3% do tempo total · 10 de 171 comandos

Mais 161 comandos gastaram nele; aumente o número no topo da página para ver.

Configuration0,086 s · 0,0% do tempo total · 1 comando
#Tempo (s)% do totalgráficoSQL IDTexto
70,085790100,0c1bqf8upvjwptDELETE FROM TAB_0049 A WHERE A.COL_0086 = :B1
Não classificado0,010 s · 0,0% do tempo total · 1 comando
Application0,002 s · 0,0% do tempo total · 1 comando
Other0,000 s · 0,0% do tempo total · 1 comando

Comandos SQL por contribuição

Contribuição: duração das chamadas de nível 0 do comando somada às esperas entre chamadas no cursor dele. Inclusivo: soma de todas as chamadas do comando, em qualquer profundidade.

#Contribuição (s)% do totalInclusivo (s)TipoProf.ExecFetchLinhasLIOPIOSQL IDTexto
1 4,462 0,4 8,467 SELECT 2 44.591 44.591 44.574 44.605 0 4yrf1rq97m3vy SELECT *** FROM TAB_0072 WHERE COL_0064 = COL_0075.ROT_0019() AND COL_0205 = :B2 AND COL_0206 = :B1 AND COL_0032 = COL_0089.COL_0090() AND COL_0039 = COL_0089.R…
2 2,615 0,3 4,786 SELECT 2 44.590 44.590 44.575 44.605 0 gfhr7c2b942m8 SELECT *** FROM TAB_0074 WHERE COL_0064 = COL_0075.ROT_0019() AND COL_0412 = :B1 AND COL_0032 = COL_0089.COL_0090() AND COL_0039 = COL_0089.ROT_0020() AND ROWNU…
3 2,043 0,2 4,247 SELECT 2 7.975 7.975 0 7.991 0 c1q763kjacnm1 SELECT X49.COL_0402, X49.COL_0403, SUM(X49.COL_0226) COL_0226, SUM(X49.COL_0227) COL_0227, X49.COL_0404, X49.COL_0405, X49.COL_0406, X49.COL_0407 FROM TAB_0093 …
4 1,631 0,2 3,047 INSERT 2 35 0 52.500 178.360 63 5jh16c5vkcn6b INSERT /*+ SYS_DL_CURSOR */ INTO TAB_0049 (COL_0417, COL_0086, COL_0107, COL_0109, COL_0418, COL_0044, COL_0105) VALUES (SEQ_007.NEXTVAL, :B1 , :B2 , :B3 , :B4 …
5 0,587 0,1 740,853 SELECT 2 1 126 63.000 7.669.408 3.528.057 d7sadnr6zu6k9 WITH TAB_0082 AS (SELECT DISTINCT COL_0265.COL_0266 , COL_0265.COL_0032 , COL_0265.COL_0039 , COL_0265.COL_0267 , COL_0265.COL_0268 , COL_0265.COL_0269 , COL_02…
6 0,012 0,0 0,253 COMMIT 1 41 0 0 39 0 7j4pr906pvfwd COMMIT
7 0,000 0,0 102,905 DELETE 1 1 0 2.953.545 13.173.523 66.988 c1bqf8upvjwpt DELETE FROM TAB_0049 A WHERE A.COL_0086 = :B1
8 0,000 0,0 1,311 UPDATE 1 2.631 0 2.631 7.935 0 a4tzy6psxru35 update seq$ set increment$=:2,minvalue=:3,maxvalue=:4,cycle#=:5,order$=:6,cache=:7,highwater=:8,audit$=:9,flags=:10 where obj#=:1
9 0,000 0,0 0,343 INSERT 1 43 0 43 549 47 cm2m46jyu6a10 INSERT INTO TAB_0069 (COL_0247, COL_0064, COL_0248, COL_0249, COL_0105, ROT_0008,COL_0044, COL_0250) VALUES (:B8 , :B7 , :B6 , :B5 , :B4 , :B3 ,:B2 , :B1 )
10 0,000 0,0 0,211 SELECT 2 868 868 102 970 0 70uypyj7247nz SELECT COL_0401 FROM TAB_0092 WHERE COL_0204 = :B5 AND COL_0032 = :B4 AND COL_0039 = :B3 AND COL_0398 = :B2 AND COL_0235 = :B1

Mostrando 10 de 171 comandos; aumente o número no topo da página para ver mais.

Tempo não atribuído a nenhum comando: 1.017,699 s.

Tipos de chamada

ChamadaQuantidadeCPU (s)Duração (s)Disco (PIO)Consistente (CR)Corrente (CU)LinhasMisses
EXEC102.658171,056813,5763.425.4127.301.50513.488.8333.008.85676
FETCH99.91517,53953,717169.791378.37969154.5430
PARSE3940,1260,219016010162
CLOSE102.6560,0540,10600000

Chamadas de nível 0 mais longas

ChamadaDuração (s)CPU (s)Início (+s)LinhaLIOPIOLinhasComando
PARSE 0,043966 0,025505 42,544 623 54 0 0 #16 SELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_…
EXEC 0,012626 0,003546 42,588 634 34 0 0 #16 SELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_…
FETCH 0,004895 0,002326 21,713 296 41 0 1 #34 Select PKG_0003.ROT_0003 ( '***' , TO_DATE('***','***') , '***' ,…
PARSE 0,004356 0,002675 74,823 976 0 0 0 #53 DECLARE COL_0074 INTEGER; ROT_0010 COL_0075.COL_0076; BEGIN FOR c1 IN (SELECT * FROM TAB_0021 WHERE COL_0064 = *** AND…
PARSE 0,003858 0,002369 42,467 381 0 0 0 #54 SELECT PKG_0005.ROT_0006(), PKG_0005.ROT_0007(), PKG_0005.ROT_0008() FROM dual
PARSE 0,003855 0,002364 21,709 275 0 0 0 #34 Select PKG_0003.ROT_0003 ( '***' , TO_DATE('***','***') , '***' ,…
PARSE 0,003061 0,001827 18,483 122 2 0 0 #60 Select COL_0017 From TAB_0002 where COL_0018 ='***'
PARSE 0,002153 0,001315 42,487 392 0 0 0 #78 SELECT PKG_0005.ROT_0009() FROM dual
EXEC 0,001898 0,000967 42,839 690 34 0 0 #16 SELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_…
EXEC 0,001776 0,001096 74,810 933 12 0 1 4 variantes #57 INSERT INTO TAB_0021 ( COL_0070 , … :n , :s , null , :s , :s , :s )
PARSE 0,001677 0,001011 19,153 197 0 0 0 #95 select COL_0027 from TAB_0005 where COL_0028 ='***'
PARSE 0,001465 0,000882 19,161 212 0 0 0 #90 select COL_0029 , COL_0030 from TAB_0006 where COL_0031 ='***'
PARSE 0,001460 0,000839 50,493 745 0 0 0 #106 SELECT COL_0058.COL_0059, COL_0058.COL_0059 || '***' || COL_0058.COL_0060 FROM TAB_0018 COL_0058 WHERE ((COL_0058.COL_00…
PARSE 0,001355 0,000742 21,764 336 0 0 0 #111 Select COL_0046 from TAB_0010 Where COL_0047 ='***'
PARSE 0,001341 0,000808 52,147 762 0 0 0 #107 SELECT COL_0061.COL_0062, COL_0061.COL_0062 || '***' || COL_0061.COL_0063 FROM TAB_0019 COL_0061 WHERE COL_0059 = '***
PARSE 0,001322 0,000746 21,723 307 0 0 0 #98 SELECT TAB_0006.COL_0031 , TAB_0006.COL_0029 FROM TAB_0006 WHERE lower ( TAB_0006.COL_0045 ) =lower ( '***'…
FETCH 0,001237 0,000630 42,601 637 76 1 1 #16 SELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_…
PARSE 0,001099 0,000662 21,837 351 0 0 0 #127 Select p.COL_0046 from TAB_0010 p where p.COL_0047 ='***'
FETCH 0,001042 0,000544 42,840 693 76 1 1 #16 SELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_…
PARSE 0,001022 0,000637 74,778 824 0 0 0 #124 INSERT INTO TAB_0020 ( COL_0064 , COL_0065 , COL_0066 , COL_0067 , COL_0068 , COL_0032 , COL_0069 ) VALUES ( *** , '***
PARSE 0,000912 0,000550 74,765 795 0 0 0 2 variantes #117 SELECT SEQ_001.NEXTVAL FROM dual
PARSE 0,000563 0,000282 74,819 965 0 0 0 8 variantes #55 INSERT INTO TAB_0021 ( COL_0070 , … :n , :s , :s , :s , :s , :s )
FETCH 0,000542 0,000334 21,702 263 2 0 1 #138 SELECT PKG_0002.ROT_0002 ( nvl ( LTRIM ( RTRIM ( COL_0034 ) ) , '***' ) , '***' ) , COL_0035 , COL_0036 , C…
PARSE 0,000533 0,000326 74,771 810 0 0 0 #143 SELECT SYSDATE FROM dual
PARSE 0,000445 0,000110 0,025 101 0 0 0 #140 BEGIN PKG_0001.ROT_0001( '***' );END;
FETCH 0,000387 0,000239 42,471 384 0 0 1 #54 SELECT PKG_0005.ROT_0006(), PKG_0005.ROT_0007(), PKG_0005.ROT_0008() FROM dual
FETCH 0,000273 0,000172 21,728 312 19 0 0 #98 SELECT TAB_0006.COL_0031 , TAB_0006.COL_0029 FROM TAB_0006 WHERE lower ( TAB_0006.COL_0045 ) =lower ( '***'…
EXEC 0,000222 0,000135 74,779 825 8 0 1 #124 INSERT INTO TAB_0020 ( COL_0064 , COL_0065 , COL_0066 , COL_0067 , COL_0068 , COL_0032 , COL_0069 ) VALUES ( *** , '***
FETCH 0,000210 0,000133 19,166 217 19 0 0 #90 select COL_0029 , COL_0030 from TAB_0006 where COL_0031 ='***'
EXEC 0,000191 0,000116 42,449 369 0 0 1 #145 BEGIN PKG_0004.ROT_0005( '***', '***' ); PKG_0004.ROT_0005( '***', '***' ); PKG_0004.ROT_0005( '***', …
FETCH 0,000190 0,000122 21,724 310 12 0 1 #98 SELECT TAB_0006.COL_0031 , TAB_0006.COL_0029 FROM TAB_0006 WHERE lower ( TAB_0006.COL_0045 ) =lower ( '***'…
FETCH 0,000188 0,000118 19,163 215 12 0 1 #90 select COL_0029 , COL_0030 from TAB_0006 where COL_0031 ='***'
EXEC 0,000172 0,000063 0,025 102 0 0 1 #140 BEGIN PKG_0001.ROT_0001( '***' );END;
PARSE 0,000166 0,000099 21,701 260 0 0 0 #138 SELECT PKG_0002.ROT_0002 ( nvl ( LTRIM ( RTRIM ( COL_0034 ) ) , '***' ) , '***' ) , COL_0035 , COL_0036 , C…
PARSE 0,000154 0,000095 42,838 687 0 0 0 #16 SELECT DISTINCT a.COL_0056, a.COL_0057 FROM TAB_0013 a, (select distinct COL_0032,COL_0039 from TAB_0014) b WHERE b.COL_…
PARSE 0,000153 0,000089 18,585 167 0 0 0 #148 SELECT COL_0022 , COL_0023 , COL_0024 , COL_0025 FROM TAB_0004
FETCH 0,000148 0,000089 18,521 140 4 0 1 #149 SELECT TAB_0003.COL_0019 FROM TAB_0003 WHERE TAB_0003.COL_0020 =( SELECT max ( TAB_0003.COL_0020 ) FROM TAB_0003 )
PARSE 0,000133 0,000073 18,521 137 0 0 0 #149 SELECT TAB_0003.COL_0019 FROM TAB_0003 WHERE TAB_0003.COL_0020 =( SELECT max ( TAB_0003.COL_0020 ) FROM TAB_0003 )
PARSE 0,000129 0,000077 18,624 181 0 0 0 #151 SELECT COL_0026 , COL_0025 FROM TAB_0004
PARSE 0,000128 0,000077 20,478 228 0 0 0 #146 SELECT TAB_0007.COL_0032 , TAB_0007.COL_0033 , TAB_0007.COL_0034 FROM TAB_0007 WHERE ( TAB_0007.COL_0032 ='***' )
PARSE 0,000126 0,000075 18,581 153 0 0 0 #152 SELECT COL_0018 , COL_0021 FROM TAB_0002
FETCH 0,000116 0,000069 18,585 170 2 0 1 #148 SELECT COL_0022 , COL_0023 , COL_0024 , COL_0025 FROM TAB_0004
EXEC 0,000109 0,000065 42,452 374 0 0 1 #145 BEGIN PKG_0004.ROT_0005( '***', '***' ); PKG_0004.ROT_0005( '***', '***' ); PKG_0004.ROT_0005( '***', …
PARSE 0,000108 0,000065 42,449 368 0 0 0 #145 BEGIN PKG_0004.ROT_0005( '***', '***' ); PKG_0004.ROT_0005( '***', '***' ); PKG_0004.ROT_0005( '***', …
FETCH 0,000094 0,000056 18,581 156 2 0 1 #152 SELECT COL_0018 , COL_0021 FROM TAB_0002
FETCH 0,000093 0,000051 18,624 184 2 0 1 #151 SELECT COL_0026 , COL_0025 FROM TAB_0004
PARSE 0,000088 0,000052 21,586 245 0 0 0 #146 SELECT TAB_0007.COL_0032 , TAB_0007.COL_0033 , TAB_0007.COL_0034 FROM TAB_0007 WHERE ( TAB_0007.COL_0032 ='***' )
FETCH 0,000085 0,000051 19,155 200 3 0 1 #95 select COL_0027 from TAB_0005 where COL_0028 ='***'
FETCH 0,000084 0,000048 52,149 765 2 0 1 #107 SELECT COL_0061.COL_0062, COL_0061.COL_0062 || '***' || COL_0061.COL_0063 FROM TAB_0019 COL_0061 WHERE COL_0059 = '***
EXEC 0,000073 0,000043 19,154 198 0 0 0 #95 select COL_0027 from TAB_0005 where COL_0028 ='***'

Distribuição de latência

Faixas em potências de 10, por evento de espera e por tipo de chamada. Mostra se o tempo vem de muitas ocorrências rápidas ou de poucas lentas.

db file sequential read323.268 ocorrências · 365,866 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms271.10983,999,93768427,3
1 ms ≤ e < 10 ms47.69914,8186,76657151,0
10 ms ≤ e < 100 ms4.4501,477,58206021,2
100 ms ≤ e < 1 s100,01,5795550,4
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
direct path read5.357 ocorrências · 82,616 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms60,10,0041720,0
1 ms ≤ e < 10 ms1.42426,69,21795011,2
10 ms ≤ e < 100 ms3.91573,171,69615886,8
100 ms ≤ e < 1 s120,21,6975712,1
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
SQL*Net message from client68 ocorrências · 74,696 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms4972,10,1326240,2
10 ms ≤ e < 100 ms913,20,3161030,4
100 ms ≤ e < 1 s34,40,8671621,2
1 s ≤ e < 10 s45,911,71301915,7
10 s ≤ e < 100 s34,461,66713082,6
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
direct path read temp8.825 ocorrências · 60,291 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs10,00,0000080,0
10 µs ≤ e < 100 µs180,20,0009070,0
100 µs ≤ e < 1 ms1.17413,30,7733951,3
1 ms ≤ e < 10 ms6.02768,334,60028457,4
10 ms ≤ e < 100 ms1.60518,224,91650641,3
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
db file scattered read1.019 ocorrências · 12,718 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms30429,82,32195818,3
10 ms ≤ e < 100 ms71570,210,39605581,7
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
direct path write temp2.950 ocorrências · 7,639 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs20,10,0000150,0
10 µs ≤ e < 100 µs391,30,0022120,0
100 µs ≤ e < 1 ms57219,40,3289224,3
1 ms ≤ e < 10 ms2.23875,95,25019568,7
10 ms ≤ e < 100 ms983,31,95498025,6
100 ms ≤ e < 1 s10,00,1023381,3
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
log file switch completion2 ocorrências · 0,053 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms00,00,0000000,0
10 ms ≤ e < 100 ms2100,00,052671100,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
log file switch (private strand flush incomplete)2 ocorrências · 0,033 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms150,00,00820224,8
10 ms ≤ e < 100 ms150,00,02491775,2
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
CSS initialization1 ocorrências · 0,009 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms1100,00,008608100,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
enq: RO - fast object reuse3 ocorrências · 0,002 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms266,70,00071530,0
1 ms ≤ e < 10 ms133,30,00166870,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
CSS operation: action1 ocorrências · 0,001 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms1100,00,001118100,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
Disk file operations I/O36 ocorrências · 0,001 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs1233,30,0000686,8
10 µs ≤ e < 100 µs2466,70,00092593,2
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms00,00,0000000,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
CSS operation: query6 ocorrências · 0,000 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs350,00,0000000,0
1 µs ≤ e < 10 µs116,70,0000020,5
10 µs ≤ e < 100 µs116,70,0000122,9
100 µs ≤ e < 1 ms116,70,00039596,6
1 ms ≤ e < 10 ms00,00,0000000,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
reliable message2 ocorrências · 0,000 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs00,00,0000000,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms2100,00,000356100,0
1 ms ≤ e < 10 ms00,00,0000000,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
SQL*Net message to client68 ocorrências · 0,000 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs57,40,0000000,0
1 µs ≤ e < 10 µs6392,60,000159100,0
10 µs ≤ e < 100 µs00,00,0000000,0
100 µs ≤ e < 1 ms00,00,0000000,0
1 ms ≤ e < 10 ms00,00,0000000,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
EXEC (duração e da chamada)102.658 ocorrências · 813,576 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs240,00,0001550,0
10 µs ≤ e < 100 µs39.37338,43,0290080,4
100 µs ≤ e < 1 ms62.49960,913,7023191,7
1 ms ≤ e < 10 ms6920,71,3104470,2
10 ms ≤ e < 100 ms610,12,9993530,4
100 ms ≤ e < 1 s70,00,9701040,1
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s20,0791,56413997,3
1.000 s ≤ e < ∞00,00,0000000,0
FETCH (duração e da chamada)99.915 ocorrências · 53,717 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs64.12664,20,4660980,9
10 µs ≤ e < 100 µs34.97235,00,8462181,6
100 µs ≤ e < 1 ms6730,70,1395460,3
1 ms ≤ e < 10 ms280,00,1836200,3
10 ms ≤ e < 100 ms1150,11,5282852,8
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s10,050,55370394,1
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
PARSE (duração e da chamada)394 ocorrências · 0,219 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs00,00,0000000,0
1 µs ≤ e < 10 µs12030,50,0009170,4
10 µs ≤ e < 100 µs10125,60,0025691,2
100 µs ≤ e < 1 ms13233,50,04670121,3
1 ms ≤ e < 10 ms389,60,09325342,5
10 ms ≤ e < 100 ms30,80,07586034,6
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0
CLOSE (duração e da chamada)102.656 ocorrências · 0,106 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 1 µs17.50917,10,0000000,0
1 µs ≤ e < 10 µs84.94282,70,09850892,8
10 µs ≤ e < 100 µs1920,20,0047214,4
100 µs ≤ e < 1 ms130,00,0029252,8
1 ms ≤ e < 10 ms00,00,0000000,0
10 ms ≤ e < 100 ms00,00,0000000,0
100 ms ≤ e < 1 s00,00,0000000,0
1 s ≤ e < 10 s00,00,0000000,0
10 s ≤ e < 100 s00,00,0000000,0
100 s ≤ e < 1.000 s00,00,0000000,0
1.000 s ≤ e < ∞00,00,0000000,0

Leituras

leituras (faixas de disco)338.469 ocorrências · 521,491 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 5 ms308.62091,2199,90469738,3
5 ms ≤ e < 10 ms19.1425,7133,71823225,6
10 ms ≤ e < 15 ms5.7831,770,41330313,5
15 ms ≤ e < 20 ms2.6040,844,6126168,6
20 ms ≤ e < 25 ms1.0480,323,2583074,5
25 ms ≤ e < 50 ms1.0620,334,0202856,5
50 ms ≤ e < 100 ms1880,112,2862682,4
100 ms ≤ e < 1 s220,03,2771260,6
1 s ≤ e < ∞00,00,0000000,0
leituras (faixas de SSD)338.469 ocorrências · 521,491 s
Faixa da duraçãoOcorrências% ocorr.Duração (s)% duração
0 ≤ e < 15 µs30,00,0000310,0
15 µs ≤ e < 25 µs10,00,0000230,0
25 µs ≤ e < 50 µs60,00,0001980,0
50 µs ≤ e < 100 µs90,00,0006630,0
100 µs ≤ e < 500 µs228.96167,671,67426613,7
500 µs ≤ e < 1 ms43.32812,829,0409855,6
1 ms ≤ e < 10 ms55.45416,4232,90676344,7
10 ms ≤ e < ∞10.7073,2187,86790536,0

Primeira leitura e releitura do mesmo bloco

Releitura frequente do mesmo bloco indica cache pequeno ou acesso repetido ao mesmo dado.

EventoBlocos distintos1ª leitura1ª leitura (s)ReleiturasReleituras (s)
db file sequential read323.225323.225365,662430,204
db file scattered read1.0191.01912,71800,000

Objetos com mais tempo de espera

Pelo obj# das esperas. O nome vem das linhas STAT do próprio trace, quando existem.

obj#NomeEspera (s)OcorrênciasEventos principais
192892404,790290.860db file sequential read · direct path read · direct path read temp
20284966,67033.428db file sequential read
19289315,8379.559db file sequential read · direct path read · direct path write temp
2028543,822873db file sequential read
2019990,696419direct path read temp · db file sequential read · Disk file operations I/O
2012870,628475direct path read temp · db file sequential read · Disk file operations I/O
1927460,533192db file sequential read · direct path read temp · Disk file operations I/O
2012860,328322direct path read temp · db file sequential read
2028670,30547db file sequential read · Disk file operations I/O
2028650,14336db file sequential read · Disk file operations I/O
201288IDX_00340,06413db file sequential read
192133TAB_00650,0376direct path read temp · db file sequential read
201349IDX_00350,0254db file sequential read
1914730,0125db file sequential read · Disk file operations I/O
190484TAB_00350,0092db file sequential read
190757TAB_00630,0091db file sequential read
199392IDX_00600,0081db file sequential read
2028530,0061db file sequential read
190981TAB_00950,0061db file sequential read
1918160,0063db file sequential read · Disk file operations I/O
202751IDX_00360,0051db file sequential read
2012960,0051db file sequential read
2012990,0041db file sequential read
2012930,0041db file sequential read
198940IDX_00450,0041db file sequential read
1918150,0032db file sequential read · Disk file operations I/O
2013150,0021db file sequential read

Textos compartilháveis

Comandos que só diferem em literais. Com bind variables, um único cursor serviria a todos e os PARSE com hard parse cairiam.

TextosPARSEPARSE (s)Total (s)SQL IDsExemplo
440,0030,005ocultos pela máscara#48 CREATE GLOBAL TEMPORARY T
880,0030,004ocultos pela máscara#55 INSERT INTO TAB_0021 ( COL_0070 , … :n , :s , :s , :s , :s , :s )
440,0020,004ocultos pela máscara#57 INSERT INTO TAB_0021 ( COL_0070 , … :n , :s , null , :s , :s , :s )
220,0010,001ocultos pela máscara#117 SELECT SEQ_001.NEXTVAL FROM dual

Leitura de um bloco

db file sequential read

Lê um bloco do disco para o buffer cache: acesso por índice, à tabela via ROWID, a undo ou a cabeçalho de segmento.

Normal quando
Acesso por índice a poucos blocos, com média de 0,1 a 1 ms em SSD ou flash e de 5 a 10 ms em disco.
Preocupa quando
Muitas leituras: o plano lê blocos demais (índice pouco seletivo, NESTED LOOPS com muitas linhas). Média alta: armazenamento lento ou disputado.
Onde olhar

CPU nas chamadas EXEC

CPU: EXEC dbcalls

Tempo de CPU da sessão na execução: DML, abertura do cursor e corpo de blocos PL/SQL. Alto em PL/SQL: lógica pesada ou SQL dentro de laço.

Normal quando
É parte do trabalho normal do banco.
Preocupa quando
Domina o tempo total: comece pelos comandos que mais gastam CPU e pelas leituras lógicas deles.
Onde olhar

Tempo não contabilizado dentro das chamadas

tempo não contabilizado dentro das chamadas

Parte da duração da chamada (e=) que não é CPU (c=) nem espera registrada dentro dela.

Normal quando
Pequeno. Valor negativo vem da granularidade da medida de CPU, não de problema.
Preocupa quando
Soma muito: fila de CPU (mais processos prontos que núcleos), paginação de memória (swap) ou código sem instrumentação de espera.
Onde olhar

Tempo não contabilizado entre chamadas

tempo não contabilizado entre chamadas

Tempo entre o fim de uma chamada de nível 0 e o início da próxima sem nenhuma espera registrada. É a diferença entre o tempo total e a soma das chamadas e das esperas entre elas.

Normal quando
Pequeno: frações de milissegundo por chamada.
Preocupa quando
Soma muito: o trace foi ligado ou desligado no meio, o processo ficou fora da CPU (servidor saturado), a aplicação demorou sem a espera de cliente aparecer, ou falta arquivo da coleta.
Onde olhar

Leitura direta para a PGA

direct path read

Lê blocos do disco direto para a memória da sessão, sem passar pelo buffer cache: full scan grande em série ou consulta paralela.

Normal quando
Leitura completa de tabela grande num processo de lote.
Preocupa quando
Aparece em tela de usuário ou dentro de laço: full scan grande repetido, tabela lida inteira para devolver poucas linhas.
Onde olhar

Esperando a aplicação

SQL*Net message from client

A sessão terminou a chamada anterior e espera o próximo pedido da aplicação. É tempo da aplicação, da rede e do usuário, não do banco.

Normal quando
Aparece entre todas as chamadas. Esperas longas e isoladas são o usuário pensando ou a aplicação ocupada com outra coisa.
Preocupa quando
Domina o tempo total com muitas esperas curtas: a aplicação conversa demais com o banco (uma linha por chamada, SQL dentro de laço). Com poucas esperas longas, a demora está na aplicação, e ajuste de SQL não resolve.
Onde olhar

Lendo do TEMP

direct path read temp

Lê da tablespace temporária o que uma ordenação, um hash join ou uma tabela temporária gravou lá.

Normal quando
Ordenações e junções grandes, em lote.
Preocupa quando
A operação devia caber na memória da sessão: PGA pequena para ela, ou volume maior que o necessário (junção errada, ordenação de linhas demais).
Onde olhar

CPU nas chamadas FETCH

CPU: FETCH dbcalls

Tempo de CPU da sessão na busca das linhas. Alto: leituras lógicas demais (LIO), em geral um plano que lê muito mais blocos do que as linhas devolvidas pedem.

Normal quando
É parte do trabalho normal do banco.
Preocupa quando
Domina o tempo total: comece pelos comandos que mais gastam CPU e pelas leituras lógicas deles.
Onde olhar

Leitura de vários blocos

db file scattered read

Lê vários blocos contíguos de uma vez para o buffer cache: full table scan ou index fast full scan.

Normal quando
Full scan de tabela pequena ou média, que cabe no cache.
Preocupa quando
Full scan de tabela grande para devolver poucas linhas: falta índice, ou o índice não é usado.
Onde olhar

Gravando no TEMP

direct path write temp

Grava no TEMP a parte de uma ordenação ou de um hash join que não coube na PGA.

Normal quando
Operações grandes, em lote.
Preocupa quando
O mesmo que a leitura do TEMP: PGA pequena ou volume alto.
Onde olhar

CPU nas chamadas PARSE

CPU: PARSE dbcalls

Tempo de CPU da sessão na análise do SQL (parse). Alto: hard parse por SQL sem bind, ou parse repetido do mesmo cursor (abrir e fechar a cada execução).

Normal quando
É parte do trabalho normal do banco.
Preocupa quando
Domina o tempo total: comece pelos comandos que mais gastam CPU e pelas leituras lógicas deles.
Onde olhar

CPU nas chamadas CLOSE

CPU: CLOSE dbcalls

Tempo de CPU da sessão no fechamento do cursor. Quase sempre pequeno.

Normal quando
É parte do trabalho normal do banco.
Preocupa quando
Domina o tempo total: comece pelos comandos que mais gastam CPU e pelas leituras lógicas deles.
Onde olhar

Troca de redo log

log file switch completion

A sessão espera a troca do arquivo de redo log terminar.

Normal quando
Raro: uma espera curta a cada troca.
Preocupa quando
Redo logs pequenos para o volume gerado: trocas frequentes.
Onde olhar

Configuration (configuração)

Configuration

Esperas causadas por um limite de configuração: redo log pequeno, buffer cache, ITL, cache de sequência, espaço.

Normal quando
Raro.
Preocupa quando
O evento aponta o parâmetro ou o objeto a ajustar.
Onde olhar
  • Qual evento, e o que ele pede: Por classe de espera, linha desta classe: coluna Principais componentes

Não classificado

Não classificado

Eventos que o motor não sabe classificar.

Normal quando
Soma pouco tempo.
Preocupa quando
Soma muito: a classe do evento em v$event_name diz onde ele se encaixa.
Onde olhar
  • Classe do evento: v$event_name.wait_class, no banco

Limpeza de buffers (TRUNCATE ou DROP)

enq: RO - fast object reuse

TRUNCATE ou DROP esperando o banco descartar os blocos do objeto que estão no buffer cache.

Normal quando
Soma pouco tempo.
Preocupa quando
TRUNCATE dentro de laço, ou buffer cache muito grande para varrer.
Onde olhar

Abrindo ou fechando arquivos

Disk file operations I/O

Operações no arquivo (abrir, fechar, consultar tamanho), não leitura de dados.

Normal quando
Soma pouco tempo.
Preocupa quando
Soma muito: muitos arquivos abertos e fechados, ou sistema de arquivos lento.
Onde olhar

Mensagem entre processos

reliable message

A sessão mandou uma mensagem a processos de fundo ou a outras instâncias e espera a confirmação.

Normal quando
Soma pouco tempo.
Preocupa quando
Soma muito: TRUNCATE, result cache ou mudança de parâmetro em RAC.
Onde olhar

Mandando a resposta

SQL*Net message to client

Tempo para entregar um pacote de resposta ao sistema operacional. Não mede a rede até o cliente, só a entrega ao SO.

Normal quando
Microssegundos por ocorrência, uma por chamada.
Preocupa quando
A média sobe para milissegundos: buffer de rede do servidor cheio ou rede saturada.
Onde olhar

User I/O (leitura e escrita de dados)

User I/O

Leituras e gravações de dados feitas pela própria sessão.

Normal quando
Algum I/O sempre há, com média baixa por leitura.
Preocupa quando
Blocos demais (o plano lê mais do que precisa) ou tempo médio alto por leitura (armazenamento lento).
Onde olhar

Não contabilizado

Não contabilizado

Tempo que o trace não explica por CPU nem por espera: dentro das chamadas e entre elas.

Normal quando
Pequeno.
Preocupa quando
Fila de CPU, paginação, lacunas no trace ou arquivo faltando na coleta.
Onde olhar

CPU

CPU

Tempo de CPU das chamadas ao banco (o c= de PARSE, EXEC e FETCH).

Normal quando
É o trabalho do banco.
Preocupa quando
Soma muito: leituras lógicas demais (plano), parse em excesso ou PL/SQL pesado.
Onde olhar

Idle (ociosa)

Idle

A sessão espera trabalho. Num trace de sessão, quase sempre é SQL*Net message from client: tempo da aplicação, da rede e do usuário.

Normal quando
Aparece entre as chamadas.
Preocupa quando
Domina o tempo total e o usuário reclama da demora: a lentidão está fora do banco (aplicação, rede, idas e voltas demais).
Onde olhar

Application (aplicação)

Application

Esperas causadas pela forma como a aplicação usa o banco: linhas e tabelas bloqueadas por outra sessão, locks da aplicação, erros devolvidos.

Normal quando
Soma pouco tempo.
Preocupa quando
Sessão parada esperando outra: transações longas, mesma linha atualizada por várias sessões, chave estrangeira sem índice.
Onde olhar

Other (outros)

Other

Eventos internos sem classe mais específica.

Normal quando
Soma pouco tempo.
Preocupa quando
Soma muito: o evento é que diz a causa.
Onde olhar

Network (rede)

Network

Mandar e receber dados pelo SQL*Net, com a aplicação ou com outro banco por dblink.

Normal quando
Soma pouco tempo.
Preocupa quando
Resultados grandes, arraysize baixo ou dblink lento.
Onde olhar

SQL recursivo (comandos filhos)

SQL recursivo (comandos filhos)

Tempo das chamadas que este comando disparou: SQL dentro de funções e procedures PL/SQL, triggers e consultas ao dicionário.

Normal quando
Comando PL/SQL que executa outros SQL.
Preocupa quando
Soma muito num SELECT ou DML simples: função PL/SQL chamada por linha, ou trigger pesado.
Onde olhar
  • Quais comandos filhos e quanto custa cada um: detalhe do comando, SQL recursivo

WaitScope 1.7.0 · análise feita no servidor · relatório exportado em 29/10/2026 10:19:56