asm磁盘组增加磁盘进行扩容之后报ORA-15335: ASM metadata corruption detected in disk group ‘DATA’和ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479],磁盘组dismount,然后mount之后立马dismount掉.
Tue Jun 29 09:19:09 2021SQL> ALTER DISKGROUP DATA ADD DISK
'/dev/raw/raw5'
SIZE 102400M /* ASMCA */ NOTE: GroupBlock outside rolling migration privileged regionNOTE: Assigning number (2,1) to disk (
/dev/raw/raw5
)NOTE: requesting all-instance membership refresh
for
group=2NOTE: initializing header on grp 2 disk DATA_0001NOTE: requesting all-instance disk validation
for
group=2Tue Jun 29 09:19:11 2021NOTE: skipping rediscovery
for
group 2
/0xb0c845ce
(DATA) on
local
instance.NOTE: requesting all-instance disk validation
for
group=2NOTE: skipping rediscovery
for
group 2
/0xb0c845ce
(DATA) on
local
instance.NOTE: initiating PST update: grp = 2Tue Jun 29 09:19:16 2021GMON updating group 2 at 7
for
pid 27, osid 25020NOTE: PST update grp = 2 completed successfully NOTE: membership refresh pending
for
group 2
/0xb0c845ce
(DATA)GMON querying group 2 at 8
for
pid 18, osid 3852NOTE: cache opening disk 1 of grp 2: DATA_0001 path:
/dev/raw/raw5NOTE: Attempting voting
file
refresh on diskgroup DATANOTE: Refresh completed on diskgroup DATA. No voting
file
found.GMON querying group 2 at 9
for
pid 18, osid 3852SUCCESS: refreshed membership
for
2
/0xb0c845ce
(DATA)Tue Jun 29 09:19:20 2021SUCCESS: ALTER DISKGROUP DATA ADD DISK
'/dev/raw/raw5'
SIZE 102400M /* ASMCA */NOTE: starting rebalance of group 2
/0xb0c845ce
(DATA) at power 1Starting background process ARB0Tue Jun 29 09:19:21 2021ARB0 started with pid=33, OS
id
=25176 NOTE: assigning ARB0 to group 2
/0xb0c845ce
(DATA) with 1 parallel I
/Ocellip.ora not found.Tue Jun 29 09:19:24 2021NOTE: Attempting voting
file
refresh on diskgroup DATANOTE: Refresh completed on diskgroup DATA. No voting
file
found.Tue Jun 29 09:19:46 2021WARNING: cache
read
a corrupt block: group=2(DATA) dsk=0 blk=7 disk=0 (DATA_0000) incarn=3915953476 au=0 blk=7 count=1Errors
in
file
/u01/app/grid/diag/asm/
+asm/+ASM1
/trace/
+ASM1_arb0_25176.trc:ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]NOTE: a corrupted block from group DATA was dumped to
/u01/app/grid/diag/asm/
+asm/+ASM1
/trace/
+ASM1_arb0_25176.trcErrors
in
file
/u01/app/grid/diag/asm/
+asm/+ASM1
/trace/
+ASM1_arb0_25176.trc:ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]ERROR: cache failed to
read
group=2(DATA) dsk=0 blk=7 from disk(s): 0(DATA_0000)ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]NOTE: cache initiating offline of disk 0 group DATANOTE: process _arb0_+asm1 (25176) initiating offline of disk 0.3915953476 (DATA_0000) with mask 0x7e
in
group 2NOTE: initiating PST update: grp = 2, dsk = 0
/0xe968b544
, mask = 0x6a,
op
=
clearTue Jun 29 09:19:46 2021GMON updating disk modes
for
group 2 at 10
for
pid 33, osid 25176ERROR: Disk 0 cannot be offlined, since diskgroup has external redundancy.ERROR: too many offline disks
in
PST (grp 2)Tue Jun 29 09:19:46 2021NOTE: cache dismounting (not clean) group 2
/0xB0C845CE
(DATA) NOTE: messaging CKPT to quiesce pins Unix process pid: 25395, image: oracle@frsrac1 (B000)Tue Jun 29 09:19:46 2021NOTE: halting all I
/Os
to diskgroup 2 (DATA)Tue Jun 29 09:19:46 2021NOTE: LGWR doing non-clean dismount of group 2 (DATA)NOTE: LGWR
sync
ABA=11.10715 last written ABA 11.10715WARNING: Offline
for
disk DATA_0000
in
mode 0x7f failed.Errors
in
file
/u01/app/grid/diag/asm/
+asm/+ASM1
/trace/
+ASM1_arb0_25176.trc (incident=54665):ORA-15335: ASM metadata corruption detected
in
disk group
'DATA'ORA-15130: diskgroup
"DATA"
is being dismountedORA-15066: offlining disk
"DATA_0000"
in
group
"DATA"
may result
in
a data lossORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]Incident details
in
:
/u01/app/grid/diag/asm/
+asm/+ASM1
/incident/incdir_54665/
+ASM1_arb0_25176_i54665.trcTue Jun 29 09:19:46 2021kjbdomdet send to inst 2detach from dom 2, sending detach message to inst 2Tue Jun 29 09:19:46 2021List of instances:
1 2Dirty detach reconfiguration started (new ddet inc 1, cluster inc 24)
Global Resource Directory partially frozen
for
dirty detach* dirty detach - domain 2 invalid = TRUE
796 GCS resources traversed, 0 cancelledDirty Detach Reconfiguration completeTue Jun 29 09:19:46 2021WARNING: dirty detached from domain 2NOTE: cache dismounted group 2
/0xB0C845CE
(DATA) SQL> alter diskgroup DATA dismount force /* ASM SERVER:2965915086 */ Tue Jun 29 09:19:47 2021ERROR: ORA-15130 thrown
in
ARB0
for
group number 2Errors
in
file
/u01/app/grid/diag/asm/
+asm/+ASM1
/trace/
+ASM1_arb0_25176.trc:ORA-15130: diskgroup
"DATA"
is being dismountedORA-15335: ASM metadata corruption detected
in
disk group
'DATA'ORA-15130: diskgroup
"DATA"
is being dismountedORA-15066: offlining disk
"DATA_0000"
in
group
"DATA"
may result
in
a data lossORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]ORA-15196: invalid ASM block header [kfc.c:26368] [check_kfbh] [2147483648] [7] [2183628676 != 686982479]Tue Jun 29 09:19:47 2021NOTE: stopping process ARB0Tue Jun 29 09:19:47 2021Sweep [inc][54665]: completedTue Jun 29 09:19:47 2021Sweep [inc2][54665]: completedNOTE: cache deleting context
for
group DATA 2
/0xb0c845ceErrors
in
file
/u01/app/grid/diag/asm/
+asm/+ASM1
/trace/
+ASM1_rbal_3852.trc:ORA-15130: diskgroup
"DATA"
is being dismountedGMON dismounting group 2 at 11
for
pid 27, osid 25395NOTE: Disk DATA_0000
in
mode 0x7f marked
for
de-assignmentNOTE: Disk DATA_0001
in
mode 0x7f marked
for
de-assignmentSUCCESS: diskgroup DATA was dismountedSUCCESS: alter diskgroup DATA dismount force /* ASM SERVER:2965915086 */ |
通过kfed分析报错block,确认错误
kfbh.endian: 1 ; 0x000: 0x01kfbh.hard: 130 ; 0x001: 0x82kfbh.
type
: 3 ; 0x002: KFBTYP_ALLOCTBLkfbh.datfmt: 2 ; 0x003: 0x02kfbh.block.blk: 7 ; 0x004: blk=7kfbh.block.obj: 2147483648 ; 0x008: disk=0kfbh.check: 2183628676 ; 0x00c: 0x82278784 <<======该值错误,应该为:686982479kfbh.fcn.base: 3430 ; 0x010: 0x00000d66kfbh.fcn.wrap: 0 ; 0x014: 0x00000000kfbh.spare1: 0 ; 0x018: 0x00000000kfbh.spare2: 0 ; 0x01c: 0x00000000kfdatb.aunum: 2240 ; 0x000: 0x000008c0kfdatb.shrink: 448 ; 0x004: 0x01c0kfdatb.ub2pad: 0 ; 0x006: 0x0000 |
通过修复该错误,并且禁止reblance操作[增加磁盘数据需要重新分布],mount磁盘组,然后open库,发现redo已经被覆盖(非归档),强制打开库报错
SQL> alter database
open
resetlogs;alter database
open
resetlogs*ERROR at line 1:ORA-00603: ORACLE server session terminated by fatal errorORA-00600: internal error code, arguments: [2662], [0], [2691201882], [0],[2691227745], [12583040], [], [], [], [], [], []ORA-00600: internal error code, arguments: [2662], [0], [2691201881], [0],[2691227745], [12583040], [], [], [], [], [], []ORA-01092: ORACLE instance terminated. Disconnection forcedORA-00600: internal error code, arguments: [2662], [0], [2691201879], [0],[2691227745], [12583040], [], [], [], [], [], []Process ID: 25110Session ID: 287 Serial number: 3 |
通过对scn进行处理,数据库顺利open
SQL> startup
mount
pfile=
'/tmp/pfile'
;ORACLE instance started.Total System Global Area 5044088832 bytesFixed Size 2261928 bytesVariable Size 1442843736 bytesDatabase Buffers 3590324224 bytesRedo Buffers 8658944 bytesDatabase mounted.SQL> alter database
open
;Database altered. |
来自 “ ITPUB博客 ” ,链接:https://blog.itpub.net/11478134/viewspace-2780940/ ,如需转载,请注明出处,否则将追究法律责任。