Probleme mit sdb beim RAID resync

Mordor · Oct 4, 2009

Hallo zusammen
Heute nacht ist auf meinem Server ein RAID-resync durchgeleaufen, dabei hat der Kernel folgende Meldungen ausgespuckt:

Code:

Oct  4 04:00:09 host1 kernel: [3452759.509292] sd 1:0:0:0: [sdb] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Oct  4 04:00:09 host1 kernel: [3452759.509340] sd 1:0:0:0: [sdb] Sense Key : Medium Error [current] [descriptor]
Oct  4 04:00:09 host1 kernel: [3452759.509390] Descriptor sense data with sense descriptors (in hex):
Oct  4 04:00:09 host1 kernel: [3452759.509420]         72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 
Oct  4 04:00:09 host1 kernel: [3452759.509475]         57 54 1c 91 
Oct  4 04:00:09 host1 kernel: [3452759.509505] sd 1:0:0:0: [sdb] Add. Sense: Unrecovered read error - auto reallocate failed
Oct  4 04:00:09 host1 kernel: [3452759.509555] end_request: I/O error, dev sdb, sector 1465130129
Oct  4 04:00:09 host1 kernel: [3452759.509589] ata2: EH complete
Oct  4 04:00:09 host1 kernel: [3452759.511116] sd 1:0:0:0: [sdb] 1465149168 512-byte hardware sectors (750156 MB)
Oct  4 04:00:09 host1 kernel: [3452759.511189] sd 1:0:0:0: [sdb] Write Protect is off
Oct  4 04:00:09 host1 kernel: [3452759.511189] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Oct  4 04:00:09 host1 kernel: [3452759.511202] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  4 04:00:09 host1 kernel: [3452761.659205] ata2.00: exception Emask 0x0 SAct 0x8000 SErr 0x0 action 0x0
Oct  4 04:00:09 host1 kernel: [3452761.659205] ata2.00: irq_stat 0x40000008
Oct  4 04:00:09 host1 kernel: [3452761.659205] ata2.00: cmd 60/00:78:34:24:54/04:00:57:00:00/40 tag 15 ncq 524288 in
Oct  4 04:00:09 host1 kernel: [3452761.659205]          res 41/40:00:c2:24:54/24:00:57:00:00/40 Emask 0x409 (media error) <F>
Oct  4 04:00:09 host1 kernel: [3452761.659205] ata2.00: status: { DRDY ERR }
Oct  4 04:00:09 host1 kernel: [3452761.659205] ata2.00: error: { UNC }
Oct  4 04:00:09 host1 kernel: [3452761.672009] ata2.00: configured for UDMA/133
Oct  4 04:00:09 host1 kernel: [3452761.672009] ata2: EH complete
Oct  4 04:00:09 host1 kernel: [3452761.672009] sd 1:0:0:0: [sdb] 1465149168 512-byte hardware sectors (750156 MB)
Oct  4 04:00:09 host1 kernel: [3452761.673388] sd 1:0:0:0: [sdb] Write Protect is off
Oct  4 04:00:09 host1 kernel: [3452761.673388] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Oct  4 04:00:09 host1 kernel: [3452761.673398] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  4 04:00:09 host1 kernel: [3452763.674404] ata2.00: exception Emask 0x0 SAct 0x1 SErr 0x0 action 0x0
Oct  4 04:00:09 host1 kernel: [3452763.674404] ata2.00: irq_stat 0x40000008
Oct  4 04:00:09 host1 kernel: [3452763.674404] ata2.00: cmd 60/00:00:34:24:54/04:00:57:00:00/40 tag 0 ncq 524288 in
Oct  4 04:00:09 host1 kernel: [3452763.674404]          res 41/40:00:c2:24:54/24:00:57:00:00/40 Emask 0x409 (media error) <F>
Oct  4 04:00:09 host1 kernel: [3452763.674404] ata2.00: status: { DRDY ERR }
Oct  4 04:00:09 host1 kernel: [3452763.674404] ata2.00: error: { UNC }
Oct  4 04:00:09 host1 kernel: [3452763.753679] ata2.00: configured for UDMA/133
Oct  4 04:00:09 host1 kernel: [3452763.753710] ata2: EH complete
Oct  4 04:00:09 host1 kernel: [3452763.753679] sd 1:0:0:0: [sdb] 1465149168 512-byte hardware sectors (750156 MB)
Oct  4 04:00:09 host1 kernel: [3452763.756570] sd 1:0:0:0: [sdb] Write Protect is off
Oct  4 04:00:09 host1 kernel: [3452763.756570] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Oct  4 04:00:09 host1 kernel: [3452763.756583] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  4 04:00:09 host1 kernel: [3452765.689230] ata2.00: exception Emask 0x0 SAct 0x1 SErr 0x0 action 0x0
Oct  4 04:00:09 host1 kernel: [3452765.689230] ata2.00: irq_stat 0x40000008
Oct  4 04:00:09 host1 kernel: [3452765.689230] ata2.00: cmd 60/00:00:34:24:54/04:00:57:00:00/40 tag 0 ncq 524288 in
Oct  4 04:00:09 host1 kernel: [3452765.689230]          res 41/40:00:c2:24:54/24:00:57:00:00/40 Emask 0x409 (media error) <F>
Oct  4 04:00:09 host1 kernel: [3452765.689230] ata2.00: status: { DRDY ERR }
Oct  4 04:00:09 host1 kernel: [3452765.689230] ata2.00: error: { UNC }
Oct  4 04:00:09 host1 kernel: [3452765.702035] ata2.00: configured for UDMA/133
Oct  4 04:00:09 host1 kernel: [3452765.702035] ata2: EH complete
Oct  4 04:00:09 host1 kernel: [3452765.702035] sd 1:0:0:0: [sdb] 1465149168 512-byte hardware sectors (750156 MB)
Oct  4 04:00:09 host1 kernel: [3452765.702585] sd 1:0:0:0: [sdb] Write Protect is off
Oct  4 04:00:09 host1 kernel: [3452765.702585] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Oct  4 04:00:09 host1 kernel: [3452765.702595] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  4 04:00:09 host1 kernel: [3452768.314338] ata2.00: exception Emask 0x0 SAct 0x1 SErr 0x0 action 0x0
Oct  4 04:00:09 host1 kernel: [3452768.314338] ata2.00: irq_stat 0x40000008
Oct  4 04:00:09 host1 kernel: [3452768.314338] ata2.00: cmd 60/00:00:34:24:54/04:00:57:00:00/40 tag 0 ncq 524288 in
Oct  4 04:00:09 host1 kernel: [3452768.314338]          res 41/40:00:c2:24:54/24:00:57:00:00/40 Emask 0x409 (media error) <F>
Oct  4 04:00:09 host1 kernel: [3452768.314338] ata2.00: status: { DRDY ERR }
Oct  4 04:00:09 host1 kernel: [3452768.314338] ata2.00: error: { UNC }
Oct  4 04:00:09 host1 kernel: [3452768.327139] ata2.00: configured for UDMA/133
Oct  4 04:00:09 host1 kernel: [3452768.327139] ata2: EH complete
Oct  4 04:00:09 host1 kernel: [3452768.327139] sd 1:0:0:0: [sdb] 1465149168 512-byte hardware sectors (750156 MB)
Oct  4 04:00:09 host1 kernel: [3452768.327139] sd 1:0:0:0: [sdb] Write Protect is off
Oct  4 04:00:09 host1 kernel: [3452768.327139] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Oct  4 04:00:09 host1 kernel: [3452768.327152] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  4 04:00:09 host1 kernel: [3452770.251197] ata2.00: exception Emask 0x0 SAct 0x1 SErr 0x0 action 0x0
Oct  4 04:00:09 host1 kernel: [3452770.251197] ata2.00: irq_stat 0x40000008
Oct  4 04:00:09 host1 kernel: [3452770.251197] ata2.00: cmd 60/00:00:34:24:54/04:00:57:00:00/40 tag 0 ncq 524288 in
Oct  4 04:00:09 host1 kernel: [3452770.251197]          res 41/40:00:c2:24:54/24:00:57:00:00/40 Emask 0x409 (media error) <F>
Oct  4 04:00:09 host1 kernel: [3452770.251197] ata2.00: status: { DRDY ERR }
Oct  4 04:00:09 host1 kernel: [3452770.251197] ata2.00: error: { UNC }
Oct  4 04:00:09 host1 kernel: [3452770.263907] ata2.00: configured for UDMA/133
Oct  4 04:00:09 host1 kernel: [3452770.263907] sd 1:0:0:0: [sdb] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE,SUGGEST_OK
Oct  4 04:00:09 host1 kernel: [3452770.263907] sd 1:0:0:0: [sdb] Sense Key : Medium Error [current] [descriptor]
Oct  4 04:00:09 host1 kernel: [3452770.263907] Descriptor sense data with sense descriptors (in hex):
Oct  4 04:00:09 host1 kernel: [3452770.263907]         72 03 11 04 00 00 00 0c 00 0a 80 00 00 00 00 00 
Oct  4 04:00:09 host1 kernel: [3452770.263907]         57 54 24 c2 
Oct  4 04:00:09 host1 kernel: [3452770.263907] sd 1:0:0:0: [sdb] Add. Sense: Unrecovered read error - auto reallocate failed
Oct  4 04:00:09 host1 kernel: [3452770.263907] end_request: I/O error, dev sdb, sector 1465132226
Oct  4 04:00:09 host1 kernel: [3452770.263907] ata2: EH complete
Oct  4 04:00:09 host1 kernel: [3452770.265275] sd 1:0:0:0: [sdb] 1465149168 512-byte hardware sectors (750156 MB)
Oct  4 04:00:09 host1 kernel: [3452770.268125] sd 1:0:0:0: [sdb] Write Protect is off
Oct  4 04:00:09 host1 kernel: [3452770.268125] sd 1:0:0:0: [sdb] Mode Sense: 00 3a 00 00
Oct  4 04:00:09 host1 kernel: [3452770.268134] sd 1:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
Oct  4 04:00:11 host1 kernel: [3452772.399165] md: md3: data-check done.
Oct  4 04:00:11 host1 kernel: [3452772.470334] RAID1 conf printout:
Oct  4 04:00:11 host1 kernel: [3452772.470334]  --- wd:2 rd:2
Oct  4 04:00:11 host1 kernel: [3452772.470334]  disk 0, wo:0, o:1, dev:sda4
Oct  4 04:00:11 host1 kernel: [3452772.470334]  disk 1, wo:0, o:1, dev:sdb4

Das ist nur ein Auszug. Das geht noch ein ganzes Stück so weiter.

Anscheinend scheint etwas mit der sdb nicht zu stimmen.

Ein cat /proc/mdstat gibt folgendes aus:

Code:

Personalities : [raid0] [raid1] [raid6] [raid5] [raid4] 
md3 : active raid1 sda4[0] sdb4[1]
      659154880 blocks [2/2] [UU]
      
md2 : active raid1 sda3[0] sdb3[1]
      62918464 blocks [2/2] [UU]
      
md1 : active raid1 sda2[0] sdb2[1]
      2104448 blocks [2/2] [UU]
      
md0 : active (auto-read-only) raid1 sda1[0] sdb1[1]
      8393856 blocks [2/2] [UU]
      
unused devices: <none>

Ich bin gerade mit meinem Latein am Ende. Vielleicht kann jemand weiter helfen.

System:
EQ4 von Hetzner
Debian Lenny

Ausgabe von uname -r:
2.6.26-2-amd64

Ich sag schon mal vielen Dank

Gruß Mordor

Whistler · Oct 4, 2009

Ich würde mit dem Log (die ersten 8 Zeilen reichen) den Hetzner-Support beglücken und um Austauch der 2. Festplatte bitten.

Leider kann ich der Produktseite nicht entnehmen, ob Hetzner Hot-Swap zusichert - vermutlich nicht.
Dann sollte man die Downtime soweit möglich auf eine lastschwache Zeit legen können.

Falls sensible Daten auf der Platte sind, kann man u.U. das RAID vorher auflösen und die Platte überschreiben - das würde ich aber nur im Notfall tun, weil das Risiko, durch einen Fehler das System zu schreddern, nicht vernachlässigbar ist - die Platte könnte sich bei massiven Schreibzugriffen ganz weghängen.

Nach dem Plattentausch muß das RAID halt wieder neu synchronisiert werden.

Mordor · Oct 4, 2009

Alles klar, danke.

Nur noch zum Verständnis:
Wenn ich cat /proc/mdstat aufrufe, kann ich ja anhand der beiden "U" sehen, dass die beiden Platte noch sauber laufen. Wieso zeigt da dann mdstat noch etwas falsches an? Oder ist die Platte einfach noch nicht so tod, dass sie komplett rausfällt?

Whistler · Oct 4, 2009

Deine Festplatte hatte mehrere nicht über mehrfaches Auslesen behebbare Datenfehler.
Daraufhin sind die defekten Sektoren auf andere, noch heile umgemappt worden - unter Verlust der Daten.
Deswegen hat Dein RAID1 neu synchronisiert und die Daten von der unbeschädigten sda in die Reserversektoren von sdb kopiert.

Das ist alles, was das System in dieser Situation automatisch machen kann.

Im Moment läuft alles. Trotzdem sollte man nicht viel zeit verlieren - wo defekte Sektoren sind, kommen üblicherweise schnell neue dazu.

Mordor · Oct 4, 2009

Alles klar, danke. Jetzt check ich wenigstens auch den hintergrund, warum er in den Logs zwar Fehler schreibt, jedoch das Array noch in Ordnung ist.

Danke noch mal.

Probleme mit sdb beim RAID resync

Mordor

Registered User

Whistler

Well-Known Member

Mordor

Registered User

Whistler

Well-Known Member

Mordor

Registered User