Thorsten Posted June 1, 2022 Share Posted June 1, 2022 (edited) Hallo, ich habe gerade komische Fehler im Syslog von Unraid 6.10.2. Meine USV wurde kurzeitg disconectet und SATA5 Communication Error sind aufgetreten. Kann mir jemand sagen was das ein kann oder wie ich genau das Device rausfinde was SATA5 ist. Jun 1 17:48:05 TheTwist kernel: ata5.00: exception Emask 0x11 SAct 0x7e000001 SErr 0x680100 action 0x6 frozen Jun 1 17:48:05 TheTwist kernel: ata5.00: irq_stat 0x48000008, interface fatal error Jun 1 17:48:05 TheTwist kernel: ata5: SError: { UnrecovData 10B8B BadCRC Handshk } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/40:00:78:ae:b3/05:00:ec:03:00/40 tag 0 ncq dma 688128 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/40:c8:b8:93:b3/05:00:ec:03:00/40 tag 25 ncq dma 688128 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/40:d0:f8:98:b3/05:00:ec:03:00/40 tag 26 ncq dma 688128 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/40:d8:38:9e:b3/05:00:ec:03:00/40 tag 27 ncq dma 688128 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/80:e0:78:a3:b3/00:00:ec:03:00/40 tag 28 ncq dma 65536 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/40:e8:f8:a3:b3/05:00:ec:03:00/40 tag 29 ncq dma 688128 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5.00: failed command: READ FPDMA QUEUED Jun 1 17:48:05 TheTwist kernel: ata5.00: cmd 60/40:f0:38:a9:b3/05:00:ec:03:00/40 tag 30 ncq dma 688128 in Jun 1 17:48:05 TheTwist kernel: res 40/00:f0:38:a9:b3/00:00:ec:03:00/40 Emask 0x10 (ATA bus error) Jun 1 17:48:05 TheTwist kernel: ata5.00: status: { DRDY } Jun 1 17:48:05 TheTwist kernel: ata5: hard resetting link Jun 1 17:48:05 TheTwist kernel: usb 1-6-port3: disabled by hub (EMI?), re-enabling... Jun 1 17:48:05 TheTwist kernel: usb 1-6.3: USB disconnect, device number 7 Jun 1 17:48:05 TheTwist kernel: usb 1-6.3: new full-speed USB device number 8 using xhci_hcd Jun 1 17:48:05 TheTwist kernel: ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Jun 1 17:48:05 TheTwist kernel: ata5.00: configured for UDMA/133 Jun 1 17:48:05 TheTwist kernel: ata5: EH complete Jun 1 17:48:05 TheTwist kernel: hid-generic 0003:051D:0002.000A: hiddev96,hidraw1: USB HID v1.00 Device [American Power Conversion Back-UPS RS 900G FW:879.L4 .I USB FW:L4 ] on usb-0000:02:00.0-6.3/input0 Jun 1 17:48:21 TheTwist apcupsd[8031]: Communications with UPS restored. Jun 1 18:00:11 TheTwist crond[1609]: exit status 1 from user root /usr/local/sbin/mover &> /dev/null Aktuell läuft ein Parity Check normal weiter und ohne Feher: Total size: 14 TB Elapsed time: 12 hours, 22 minutes Current position: 9.39 TB (67.1 %) Estimated speed: 183.2 MB/sec Estimated finish: 6 hours, 59 minutes Sync errors detected: 0 Was kann das sein? Bis auf die Fehler im Log kann ich nix feststellen. Das System funktioniert ohne Probleme. Auch die Festplatten haben keine erhöhten Smart Werte oder andere Meldungen. Viele Grüße Thorsten Edited June 1, 2022 by Thorsten Quote Link to comment
Thorsten Posted June 1, 2022 Author Share Posted June 1, 2022 Ich habe zu dem Fehler schon was gefunden. Aber es sagt mir nichts bzw. warum Unraid oder was auch immer versucht ein lokales SATA Gerät wie eine SSD oder HDD über mehrere Pfade anzusprechen. Weiter Infos zu dem was ich gefunden sieht man hier: https://www.thomas-krenn.com/de/wiki/SATA_exception_Emask_0x0_SAct_0x80_SErr_0x0_action_0x6_frozen Viele Grüße Thorsten Quote Link to comment
Thorsten Posted June 1, 2022 Author Share Posted June 1, 2022 (edited) Mit dem folgenden Command bekomme ich schon mal das Device raus. ls -l /sys/block/sd* lrwxrwxrwx 1 root root 0 Jun 1 06:42 /sys/block/sda -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/ lrwxrwxrwx 1 root root 0 Jun 1 06:42 /sys/block/sdb -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata1/host1/target1:0:0/1:0:0:0/block/sdb/ lrwxrwxrwx 1 root root 0 Jun 1 06:30 /sys/block/sdc -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata2/host2/target2:0:0/2:0:0:0/block/sdc/ lrwxrwxrwx 1 root root 0 Jun 1 06:42 /sys/block/sdd -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata3/host3/target3:0:0/3:0:0:0/block/sdd/ lrwxrwxrwx 1 root root 0 Jun 1 06:42 /sys/block/sde -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata4/host4/target4:0:0/4:0:0:0/block/sde/ lrwxrwxrwx 1 root root 0 Jun 1 06:42 /sys/block/sdf -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata5/host5/target5:0:0/5:0:0:0/block/sdf/ lrwxrwxrwx 1 root root 0 Jun 1 06:30 /sys/block/sdg -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata6/host6/target6:0:0/6:0:0:0/block/sdg/ Also es ist eine HDD die im Array drin ist und auf der aktuell der Parity Check läuft. Der Parity Check läuft bisher ohne Fehler. Es ist sogar die Parity Disks selber 😞 lrwxrwxrwx 1 root root 0 Jun 1 06:42 /sys/block/sdf -> ../devices/pci0000:00/0000:00:01.3/0000:02:00.1/ata5/host5/target5:0:0/5:0:0:0/block/sdf/ Das einzigste was bei den Smart Werte dieser Festplatte aufgefall ist das folgender Wert erhöht ist. 199 UDMA CRC error count 0x003e 200 200 000 Old age Always Never 2 Edited June 1, 2022 by Thorsten Quote Link to comment
i-B4se Posted June 1, 2022 Share Posted June 1, 2022 5 minutes ago, Thorsten said: UDMA CRC error count Dann hat evtl. das Sata-Kabel einen weg. Steigt der Wert aktuell oder sind das alte Werte? Quote Link to comment
Thorsten Posted June 1, 2022 Author Share Posted June 1, 2022 5 minutes ago, i-B4se said: Dann hat evtl. das Sata-Kabel einen weg. Steigt der Wert aktuell oder sind das alte Werte? Der Wert steigt nicht. Somit glaube ich das es alte Werte sind. Wenn das Kabel einen Weg hat und derzeit der Parity Check läuft und mit gut 200MB/s gelesen wird müssten ja Fehler auftreten was aber nicht der Fall ist. Quote Link to comment
Thorsten Posted June 1, 2022 Author Share Posted June 1, 2022 Das sind mal die SMART-Werte der besagten HDD außer den UDMA Error kann ich nix erkennen: smartctl 7.3 2022-02-28 r5338 [x86_64-linux-5.15.43-Unraid] (local build) Copyright (C) 2002-22, Bruce Allen, Christian Franke, www.smartmontools.org === START OF INFORMATION SECTION === Model Family: Seagate Exos X16 Device Model: ST14000NM001G Serial Number: LU WWN Device Id: Firmware Version: SN02 User Capacity: 14,000,519,643,136 bytes [14.0 TB] Sector Sizes: 512 bytes logical, 4096 bytes physical Rotation Rate: 7200 rpm Form Factor: 3.5 inches Device is: In smartctl database 7.3/5387 ATA Version is: ACS-4 (minor revision not indicated) SATA Version is: SATA 3.3, 6.0 Gb/s (current: 6.0 Gb/s) Local Time is: Wed Jun 1 20:02:53 2022 CEST SMART support is: Available - device has SMART capability. SMART support is: Enabled === START OF READ SMART DATA SECTION === SMART overall-health self-assessment test result: PASSED General SMART Values: Offline data collection status: (0x82) Offline data collection activity was completed without error. Auto Offline Data Collection: Enabled. Self-test execution status: ( 0) The previous self-test routine completed without error or no self-test has ever been run. Total time to complete Offline data collection: ( 567) seconds. Offline data collection capabilities: (0x7b) SMART execute Offline immediate. Auto Offline data collection on/off support. Suspend Offline collection upon new command. Offline surface scan supported. Self-test supported. Conveyance Self-test supported. Selective Self-test supported. SMART capabilities: (0x0003) Saves SMART data before entering power-saving mode. Supports SMART auto save timer. Error logging capability: (0x01) Error logging supported. General Purpose Logging supported. Short self-test routine recommended polling time: ( 1) minutes. Extended self-test routine recommended polling time: (1262) minutes. Conveyance self-test routine recommended polling time: ( 2) minutes. SCT capabilities: (0x70bd) SCT Status supported. SCT Error Recovery Control supported. SCT Feature Control supported. SCT Data Table supported. SMART Attributes Data Structure revision number: 10 Vendor Specific SMART Attributes with Thresholds: ID# ATTRIBUTE_NAME FLAG VALUE WORST THRESH TYPE UPDATED WHEN_FAILED RAW_VALUE 1 Raw_Read_Error_Rate 0x000f 083 064 044 Pre-fail Always - 202977928 3 Spin_Up_Time 0x0003 091 090 000 Pre-fail Always - 0 4 Start_Stop_Count 0x0032 100 100 020 Old_age Always - 318 5 Reallocated_Sector_Ct 0x0033 100 100 010 Pre-fail Always - 0 7 Seek_Error_Rate 0x000f 084 060 045 Pre-fail Always - 275134937 9 Power_On_Hours 0x0032 092 092 000 Old_age Always - 7700 10 Spin_Retry_Count 0x0013 100 100 097 Pre-fail Always - 0 12 Power_Cycle_Count 0x0032 100 100 020 Old_age Always - 312 18 Head_Health 0x000b 100 100 050 Pre-fail Always - 0 187 Reported_Uncorrect 0x0032 100 100 000 Old_age Always - 0 188 Command_Timeout 0x0032 100 100 000 Old_age Always - 0 190 Airflow_Temperature_Cel 0x0022 066 044 040 Old_age Always - 34 (Min/Max 21/35) 192 Power-Off_Retract_Count 0x0032 100 100 000 Old_age Always - 18 193 Load_Cycle_Count 0x0032 096 096 000 Old_age Always - 8231 194 Temperature_Celsius 0x0022 034 056 000 Old_age Always - 34 (0 18 0 0 0) 197 Current_Pending_Sector 0x0012 100 100 000 Old_age Always - 0 198 Offline_Uncorrectable 0x0010 100 100 000 Old_age Offline - 0 199 UDMA_CRC_Error_Count 0x003e 200 200 000 Old_age Always - 2 200 Pressure_Limit 0x0023 100 100 001 Pre-fail Always - 0 240 Head_Flying_Hours 0x0000 100 253 000 Old_age Offline - 7108h+56m+02.802s 241 Total_LBAs_Written 0x0000 100 253 000 Old_age Offline - 190549142769 242 Total_LBAs_Read 0x0000 100 253 000 Old_age Offline - 713094608271 SMART Error Log Version: 1 No Errors Logged SMART Self-test log structure revision number 1 Num Test_Description Status Remaining LifeTime(hours) LBA_of_first_error # 1 Short offline Completed without error 00% 4021 - SMART Selective self-test log data structure revision number 1 SPAN MIN_LBA MAX_LBA CURRENT_TEST_STATUS 1 0 0 Not_testing 2 0 0 Not_testing 3 0 0 Not_testing 4 0 0 Not_testing 5 0 0 Not_testing Selective self-test flags (0x0): After scanning selected spans, do NOT read-scan remainder of disk. If Selective self-test is pending on power-up, resume after 0 minute delay. Quote Link to comment
DataCollector Posted June 1, 2022 Share Posted June 1, 2022 1 hour ago, Thorsten said: UDMA_CRC_Error_Count Den Wert kurz und längerfristig im Auge behalten. Wenn der Wert steigt ist irgendwas im Signalweg zwischen Kontroller (Mainboard/Steckkarte) und Festplatte im Argen. Staubfussel, schlechter Kontakt, Vollmond. Nicht selten hilft (im ausgeschakteten Zustand) einfcah man vorsichtig ein paar mal ab+anstecken um oxidierte Kontakte zu mindern. Sollten dann immer noch steigende Werte auftreten, Komponenten (Kabel, Kontroller, Festplatte) testweise austauschen und dadurch herausfinden, was vermutlich die Ursache war. Dann entscheiden ob man damit lebt oder vielleicht die Ursache dauerhaft ersetzt. Sollte der Wert nicht steigen, ist es von selber verschwunden. Quote Link to comment
Crovaxon Posted June 8, 2022 Share Posted June 8, 2022 Ich erinnere mich dunkel daran, dass meine SATA HDDs auch recht selten CRC error SMART Fehler gemeldet hatten aber ich ansonsten nie Probleme im monatelangen Betrieb hatte. Aber das ist ne Weile her und hatte sich dann gänzlich gelegt, als ich das BIOS des Mainboards mal aktualisiert hatte, kann aber auch gut daran liegen, dass ich danach mich damit auseinander gesetzt habe, die Platten anständig schlafen zu schicken. Vorher waren meine cache SSD permanent aktiv und mein HDD array auch dauerhaft wach wegen Zugriffen von mitteilungsbedürftigen Docker Containern, die viel in ihre logs schrieben. Jetzt schlafen meine Platten fast durchweg. Quote Link to comment
Recommended Posts
Join the conversation
You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.