My Oracle Support Banner

ASM ディスクの OFFLINE によりフォアグラウンド・プロセスのCPU 使用率が高騰する (Doc ID 2225722.1)

Last updated on AUGUST 04, 2018

適用範囲:

Oracle Database - Enterprise Edition - バージョン 11.2.0.1 から 11.2.0.4 [リリース 11.2]
この文書の内容はすべてのプラットフォームに適用されます。
本文書利用上のご注意
  本文書は英語の文書 <Document 2220752.1> (最終メジャー更新日: 2017年1月16日) の日本語翻訳版です。
  英語の文書のメジャー更新に応じて本文書を随時更新いたします。

現象

ASM ディスクが OFFLINE になったときには cell アラートログに以下が出力されます。

Published: 1 events ASM OFFLINE disk of opcode 5 for diskgroup XXXXX to:

ASM Disk の OFFLINE 化が行われた際に DB インスタンスのフォアグラウンド・プロセスが SPIN し、CPU 使用率が高騰する。

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
14249 oracle 20 0 109g 24m 20m R 91.0 0.0 304:53.45 oracle <<<<<<

フォアグラウンド・プロセスのコールスタックです。(pstack で取得)

#0 0x00000000094fb8f8 in kslwtbctx ()
#1 0x00000000053e3731 in kfk_reap_ios_from_subsys ()
#2 0x0000000000ce6183 in kfkFDCleanupPriv ()
#3 0x0000000000ce523a in kfkFDCleanup () <<<<<<
#4 0x00000000053ede25 in kfkFDCleanupKsdxCb ()

フォアグラウンド・プロセスのトレースファイルにファイル・ディスクリプタのクリーンアップが行われていることを示す以下のログが記録されます。

Received ORADEBUG command (#X) 'CLEANUP_KFK_FD' from process 'Unix
process pid: XXXXX, image: <none>'

しかしながら、クリーンアップの完了を示す以下のログが出力されていません。

Finished processing ORADEBUG command (#X) 'CLEANUP_KFK_FD'

AWR レポートより、'kfk: async disk IO' が多く発生していることが確認できます。

Event Waits Time Avg(ms) time Wait Class
------------------------------ ------------ ---- ------- ------ ----------
DB CPU 1015 61.8
log file sync 347,500 467. 1 28.4 Commit
cell single block physical rea 146,203 149. 1 9.1 User I/O
kfk: async disk IO 1.150600E+09 72.6 0 4.4 System I/O <<<<<<

HANG ANALYSIS の情報から "gc buffer busy acquire" の待機が確認できます。

- dia0 trace
HANG ANALYSIS:
:
Suspected Hangs in the System
Root Chain Total Hang
Hang Hang Inst Root #hung #hung Hang Hang Resolution
ID Type Status Num Sess Sess Sess Conf Span Action
----- ---- -------- ---- ----- ----- ----- ------ ------ -------------------
2 HANG VALID 4 17413 2 2 LOW LOCAL Terminate Process

inst# SessId Ser# OSPID Event
----- ------ ----- --------- -----
4 1608 257 59201 latch: cache buffers chains
4 17413 55785 105526 gc buffer busy acquire <<<<<<
:
-------------------------------------------------------------------------------
Chain 945:
-------------------------------------------------------------------------------
Oracle session identified by:
{
instance: 4
os id: 127287
process id: 2311,
session id: 3365
session serial #: 58735
}
is waiting for 'gc buffer busy acquire' with wait info:
{
p1: 'file#'=0x4d
p2: 'block#'=0x3906b
p3: 'class#'=0x1
time in wait: 0.019508 sec (last interval)
time in wait: 47 min 45 sec (total)
heur. time in wait: 98 min 45 sec <<<<<<

変更点

ASM ディスクの OFFLINE 化が行われた場合に発生する可能性があります。

原因

To view full details, sign in with your My Oracle Support account.

Don't have a My Oracle Support account? Click to get started!


My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.