Heavy log file sequential read on an idle session and a trace of the session shows "ASM file metadata operation". (Doc ID 1925180.1)

Last updated on JUNE 03, 2016

Applies to:

Oracle Database - Enterprise Edition - Version 11.2.0.2 and later
Information in this document applies to any platform.

Symptoms

Observing heavy log file sequential read on an idle session originating from 3rd party Informatica and a trace of the session shows "ASM file metadata operation".

 

Trace file /hostname/app/oracle/diag/rdbms/prod/prod2/trace/prod2_ora_11697.trc
Oracle Database 11g Enterprise Edition Release 11.2.0.2.0 - 64bit Production
...
Unix process pid: 11697, image: oracle@test1

WAIT #0: nam='ASM file metadata operation' ela= 35 msgop=17 locn=0 p3=0 obj#=-1 tim=1408559736057555                         
WAIT #0: nam='Disk file operations I/O' ela= 50 FileOperation=2 fileno=0 filetype=3 obj#=-1 tim=1408559736057573
WAIT #0: nam='log file sequential read' ela= 1364 log#=0 block#=1 blocks=1 obj#=-1 tim=1408559736058961
WAIT #0: nam='KSV master wait' ela= 308 p1=0 p2=0 p3=0 obj#=-1 tim=1408559736059366
WAIT #0: nam='KSV master wait' ela= 563 p1=0 p2=0 p3=0 obj#=-1 tim=1408559736059949
WAIT #0: nam='ASM file metadata operation' ela= 42 msgop=18 locn=0 p3=0 obj#=-1 tim=1408559736059969
WAIT #0: nam='Disk file operations I/O' ela= 105 FileOperation=5 fileno=0 filetype=3 obj#=-1 tim=1408559736060001
WAIT #0: nam='KSV master wait' ela= 133 p1=0 p2=0 p3=0 obj#=-1 tim=1408559736060171
WAIT #0: nam='KSV master wait' ela= 729 p1=0 p2=0 p3=0 obj#=-1 tim=1408559736060944
WAIT #0: nam='ASM file metadata operation' ela= 65 msgop=17 locn=0 p3=0 obj#=-1 tim=1408559736060964
WAIT #0: nam='Disk file operations I/O' ela= 46 FileOperation=2 fileno=0 filetype=3 obj#=-1 tim=1408559736060987
WAIT #0: nam='log file sequential read' ela= 1251 log#=0 block#=1 blocks=1 obj#=-1 tim=1408559736062275
WAIT #0: nam='log file sequential read' ela= 1210 log#=0 block#=1 blocks=1 obj#=-1 tim=1408559736063533
WAIT #0: nam='SQL*Net message to client' ela= 0 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1408559736063549
WAIT #0: nam='SQL*Net message from client' ela= 208 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1408559736063769
WAIT #0: nam='log file sequential read' ela= 8991 log#=0 block#=59799 blocks=3136 obj#=-1 tim=1408559736072866
WAIT #0: nam='log file sequential read' ela= 7727 log#=0 block#=59799 blocks=3136 obj#=-1 tim=1408559736080621
WAIT #0: nam='SQL*Net message to client' ela= 1 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1408559736080649
WAIT #0: nam='SQL*Net message from client' ela= 170 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1408559736080833

Cause

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 hundreds of Community platforms