Chris Posted December 20, 2011 Posted December 20, 2011 Running 4.7 with current drive setup for quite a number of months, during which time the server has been moved to various locations around the house and thus requiring shutdowns and restarts - all without issue, until now. Without any changes to the system verion, config, drives, or anything else, one of the data hard drives seems to have "grown" and now the system reports that the parity disk is no longer the biggest. I wanted to post a screen shot, and although the forum allowed me to browse to the image and select it, there was no button so "upload" or save or whatever. I am going to paste a good portion of my syslog here (trying to stay under 20K characters), and sorry for the length ... mod can delete if needed. I can't zip and upload for the same reason I can't upload images. Dec 19 22:02:13 Media syslogd 1.4.1: restart. Dec 19 22:02:13 Media kernel: klogd 1.4.1, log source = /proc/kmsg started. Dec 19 22:02:13 Media kernel: Linux version 2.6.32.9-unRAID (root@Develop) (gcc version 4.2.3) #8 SMP Wed Nov 24 01:15:59 MST 2010 ... Dec 19 22:02:13 Media kernel: ata1: SATA max UDMA/133 abar m1024@0xfe02f000 port 0xfe02f100 irq 22 Dec 19 22:02:13 Media kernel: ata2: SATA max UDMA/133 irq_stat 0x00400040, connection status changed irq 22 Dec 19 22:02:13 Media kernel: ata3: SATA max UDMA/133 abar m1024@0xfe02f000 port 0xfe02f200 irq 22 Dec 19 22:02:13 Media kernel: ata4: SATA max UDMA/133 abar m1024@0xfe02f000 port 0xfe02f280 irq 22 Dec 19 22:02:13 Media kernel: ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Dec 19 22:02:13 Media kernel: ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Dec 19 22:02:13 Media kernel: ata4.00: ATA-8: WDC WD20EADS-00S2B0, 01.00A01, max UDMA/133 Dec 19 22:02:13 Media kernel: ata4.00: 3907029168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA Dec 19 22:02:13 Media kernel: ata3.00: ATA-8: WDC WD20EADS-00R6B0, 01.00A01, max UDMA/133 Dec 19 22:02:13 Media kernel: ata3.00: 3907029168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA Dec 19 22:02:13 Media kernel: ata4.00: configured for UDMA/133 Dec 19 22:02:13 Media kernel: ata3.00: configured for UDMA/133 Dec 19 22:02:13 Media kernel: ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Dec 19 22:02:13 Media kernel: ata2.00: ATA-8: Hitachi HDS722020ALA330, JKAOA28A, max UDMA/133 Dec 19 22:02:13 Media kernel: ata2.00: 3907029168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA Dec 19 22:02:13 Media kernel: ata2.00: configured for UDMA/133 Dec 19 22:02:13 Media kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Dec 19 22:02:13 Media kernel: ata1.00: ATA-8: WDC WD20EADS-00S2B0, 04.05G04, max UDMA/133 Dec 19 22:02:13 Media kernel: ata1.00: 3907029168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA Dec 19 22:02:13 Media kernel: ata1.00: configured for UDMA/133 Dec 19 22:02:13 Media kernel: scsi 1:0:0:0: Direct-Access ATA WDC WD20EADS-00S 04.0 PQ: 0 ANSI: 5 Dec 19 22:02:13 Media kernel: scsi 2:0:0:0: Direct-Access ATA Hitachi HDS72202 JKAO PQ: 0 ANSI: 5 Dec 19 22:02:13 Media kernel: sd 2:0:0:0: [sdb] 3907029168 512-byte logical blocks: (2.00 TB/1.81 TiB) Dec 19 22:02:13 Media kernel: sd 1:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.81 TiB) Dec 19 22:02:13 Media kernel: sd 2:0:0:0: [sdb] Write Protect is off Dec 19 22:02:13 Media kernel: sd 2:0:0:0: [sdb] Mode Sense: 00 3a 00 00 Dec 19 22:02:13 Media kernel: sd 2:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 19 22:02:13 Media kernel: sd 1:0:0:0: [sda] Write Protect is off Dec 19 22:02:13 Media kernel: sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00 Dec 19 22:02:13 Media kernel: sd 1:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 19 22:02:13 Media kernel: sdb: Dec 19 22:02:13 Media kernel: sda: Dec 19 22:02:13 Media kernel: scsi 3:0:0:0: Direct-Access ATA WDC WD20EADS-00R 01.0 PQ: 0 ANSI: 5 Dec 19 22:02:13 Media kernel: sd 3:0:0:0: [sdc] 3907029168 512-byte logical blocks: (2.00 TB/1.81 TiB) Dec 19 22:02:13 Media kernel: sd 3:0:0:0: [sdc] Write Protect is off Dec 19 22:02:13 Media kernel: scsi 4:0:0:0: Direct-Access ATA WDC WD20EADS-00S 01.0 PQ: 0 ANSI: 5 Dec 19 22:02:13 Media kernel: sd 3:0:0:0: [sdc] Mode Sense: 00 3a 00 00 Dec 19 22:02:13 Media kernel: sd 3:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 19 22:02:13 Media kernel: sdc: Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] 3907029168 512-byte logical blocks: (2.00 TB/1.81 TiB) Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Write Protect is off Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Mode Sense: 00 3a 00 00 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Dec 19 22:02:13 Media kernel: sdd: Dec 19 22:02:13 Media kernel: ata4.00: exception Emask 0x50 SAct 0x1 SErr 0x280900 action 0x6 frozen Dec 19 22:02:13 Media kernel: ata4.00: irq_stat 0x08000000, interface fatal error Dec 19 22:02:13 Media kernel: ata4: SError: { UnrecovData HostInt 10B8B BadCRC } Dec 19 22:02:13 Media kernel: ata4.00: failed command: READ FPDMA QUEUED Dec 19 22:02:13 Media kernel: ata4.00: cmd 60/08:00:00:00:00/00:00:00:00:00/40 tag 0 ncq 4096 in Dec 19 22:02:13 Media kernel: res 40/00:04:00:00:00/00:00:00:00:00/40 Emask 0x50 (ATA bus error) Dec 19 22:02:13 Media kernel: ata4.00: status: { DRDY } Dec 19 22:02:13 Media kernel: ata4: hard resetting link Dec 19 22:02:13 Media kernel: sdb1 Dec 19 22:02:13 Media kernel: sd 2:0:0:0: [sdb] Attached SCSI disk Dec 19 22:02:13 Media kernel: sdc1 Dec 19 22:02:13 Media kernel: sd 3:0:0:0: [sdc] Attached SCSI disk Dec 19 22:02:13 Media kernel: sda1 Dec 19 22:02:13 Media kernel: sd 1:0:0:0: [sda] Attached SCSI disk Dec 19 22:02:13 Media kernel: ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) Dec 19 22:02:13 Media kernel: ata4.00: configured for UDMA/133 Dec 19 22:02:13 Media kernel: ata4: EH complete Dec 19 22:02:13 Media kernel: ata4: limiting SATA link speed to 1.5 Gbps Dec 19 22:02:13 Media kernel: ata4.00: exception Emask 0x50 SAct 0x1 SErr 0x280900 action 0x6 frozen Dec 19 22:02:13 Media kernel: ata4.00: irq_stat 0x08000000, interface fatal error Dec 19 22:02:13 Media kernel: ata4: SError: { UnrecovData HostInt 10B8B BadCRC } Dec 19 22:02:13 Media kernel: ata4.00: failed command: READ FPDMA QUEUED Dec 19 22:02:13 Media kernel: ata4.00: cmd 60/08:00:00:00:00/00:00:00:00:00/40 tag 0 ncq 4096 in Dec 19 22:02:13 Media kernel: res 40/00:04:00:00:00/00:00:00:00:00/40 Emask 0x50 (ATA bus error) Dec 19 22:02:13 Media kernel: ata4.00: status: { DRDY } Dec 19 22:02:13 Media kernel: ata4: hard resetting link Dec 19 22:02:13 Media kernel: ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310) Dec 19 22:02:13 Media kernel: ata4.00: failed to IDENTIFY (I/O error, err_mask=0x100) Dec 19 22:02:13 Media kernel: ata4.00: revalidation failed (errno=-5) Dec 19 22:02:13 Media kernel: scsi 0:0:0:0: Direct-Access SanDisk Cruzer Micro 8.02 PQ: 0 ANSI: 0 CCS Dec 19 22:02:13 Media kernel: usb-storage: device scan complete Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] 7856127 512-byte logical blocks: (4.02 GB/3.74 GiB) Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] Write Protect is off Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] Mode Sense: 45 00 00 08 Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] Assuming drive cache: write through Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] Assuming drive cache: write through Dec 19 22:02:13 Media kernel: sde: sde1 Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] Assuming drive cache: write through Dec 19 22:02:13 Media kernel: sd 0:0:0:0: [sde] Attached SCSI removable disk Dec 19 22:02:13 Media kernel: ata4: hard resetting link Dec 19 22:02:13 Media kernel: ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310) Dec 19 22:02:13 Media kernel: ata4.00: failed to IDENTIFY (I/O error, err_mask=0x100) Dec 19 22:02:13 Media kernel: ata4.00: revalidation failed (errno=-5) Dec 19 22:02:13 Media kernel: ata4: hard resetting link Dec 19 22:02:13 Media kernel: ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310) Dec 19 22:02:13 Media kernel: ata4.00: failed to IDENTIFY (I/O error, err_mask=0x100) Dec 19 22:02:13 Media kernel: ata4.00: revalidation failed (errno=-5) Dec 19 22:02:13 Media kernel: ata4.00: disabled Dec 19 22:02:13 Media kernel: ata4: exception Emask 0x52 SAct 0x0 SErr 0x280d01 action 0x6 frozen t4 Dec 19 22:02:13 Media kernel: ata4: irq_stat 0x0c000000, interface fatal error Dec 19 22:02:13 Media kernel: ata4: SError: { RecovData UnrecovData Proto HostInt 10B8B BadCRC } Dec 19 22:02:13 Media kernel: ata4: hard resetting link Dec 19 22:02:13 Media kernel: ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 310) Dec 19 22:02:13 Media kernel: ata4: EH complete Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Unhandled error code Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Result: hostbyte=0x04 driverbyte=0x00 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] CDB: cdb[0]=0x28: 28 00 00 00 00 00 00 00 08 00 Dec 19 22:02:13 Media kernel: end_request: I/O error, dev sdd, sector 0 Dec 19 22:02:13 Media kernel: Buffer I/O error on device sdd, logical block 0 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Unhandled error code Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Result: hostbyte=0x04 driverbyte=0x00 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] CDB: cdb[0]=0x28: 28 00 00 00 00 00 00 00 08 00 Dec 19 22:02:13 Media kernel: end_request: I/O error, dev sdd, sector 0 Dec 19 22:02:13 Media kernel: Buffer I/O error on device sdd, logical block 0 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Unhandled error code Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Result: hostbyte=0x04 driverbyte=0x00 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] CDB: cdb[0]=0x28: 28 00 00 00 00 00 00 00 08 00 Dec 19 22:02:13 Media kernel: end_request: I/O error, dev sdd, sector 0 Dec 19 22:02:13 Media kernel: Buffer I/O error on device sdd, logical block 0 Dec 19 22:02:13 Media kernel: unable to read partition table Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] READ CAPACITY(16) failed Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Result: hostbyte=0x04 driverbyte=0x00 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Sense not available. Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] READ CAPACITY failed Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Result: hostbyte=0x04 driverbyte=0x00 Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Sense not available. Dec 19 22:02:13 Media kernel: sd 4:0:0:0: [sdd] Attached SCSI disk Dec 19 22:02:13 Media kernel: atiixp 0000:00:14.1: IDE controller (0x1002:0x439c rev 0x00) Dec 19 22:02:13 Media kernel: ATIIXP_IDE 0000:00:14.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 Dec 19 22:02:13 Media kernel: atiixp 0000:00:14.1: not 100%% native mode: will probe irqs later Dec 19 22:02:13 Media kernel: ide0: BM-DMA at 0xfa00-0xfa07 Dec 19 22:02:13 Media kernel: ide1: BM-DMA at 0xfa08-0xfa0f Dec 19 22:02:13 Media kernel: Probing IDE interface ide0... Dec 19 22:02:13 Media kernel: Probing IDE interface ide1... Dec 19 22:02:13 Media kernel: hdc: WDC WD2001FASS-00U0B0, ATA DISK drive Dec 19 22:02:13 Media kernel: hdd: ST3500630AS, ATA DISK drive Dec 19 22:02:13 Media kernel: hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO4 Dec 19 22:02:13 Media kernel: hdc: UDMA/100 mode selected Dec 19 22:02:13 Media kernel: hdd: host max PIO4 wanted PIO255(auto-tune) selected PIO4 Dec 19 22:02:13 Media kernel: hdd: UDMA/100 mode selected Dec 19 22:02:13 Media kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 Dec 19 22:02:13 Media kernel: ide1 at 0x170-0x177,0x376 on irq 15 Dec 19 22:02:13 Media kernel: sata_sil 0000:03:07.0: version 2.4 Dec 19 22:02:13 Media kernel: sata_sil 0000:03:07.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 Dec 19 22:02:13 Media kernel: sata_sil 0000:03:07.0: Applying R_ERR on DMA activate FIS errata fix Dec 19 22:02:13 Media kernel: scsi5 : sata_sil Dec 19 22:02:13 Media kernel: scsi6 : sata_sil Dec 19 22:02:13 Media kernel: scsi7 : sata_sil Dec 19 22:02:13 Media kernel: scsi8 : sata_sil Dec 19 22:02:13 Media kernel: ata5: SATA max UDMA/100 mmio m1024@0xfdcff000 tf 0xfdcff080 irq 21 Dec 19 22:02:13 Media kernel: ata6: SATA max UDMA/100 mmio m1024@0xfdcff000 tf 0xfdcff0c0 irq 21 Dec 19 22:02:13 Media kernel: ata7: SATA max UDMA/100 mmio m1024@0xfdcff000 tf 0xfdcff280 irq 21 Dec 19 22:02:13 Media kernel: ata8: SATA max UDMA/100 mmio m1024@0xfdcff000 tf 0xfdcff2c0 irq 21 Dec 19 22:02:13 Media kernel: ata5: SATA link down (SStatus 0 SControl 310) Dec 19 22:02:13 Media kernel: ata6: SATA link down (SStatus 0 SControl 310) Dec 19 22:02:13 Media kernel: ata7: SATA link down (SStatus 0 SControl 310) Dec 19 22:02:13 Media kernel: ata8: SATA link down (SStatus 0 SControl 310) Dec 19 22:02:13 Media kernel: ide-gd driver 1.18 Dec 19 22:02:13 Media kernel: hdc: max request size: 512KiB Dec 19 22:02:13 Media kernel: hdc: 3907029168 sectors (2000398 MB), CHS=65535/255/63 Dec 19 22:02:13 Media kernel: hdc: cache flushes supported Dec 19 22:02:13 Media kernel: hdc: hdc1 Dec 19 22:02:13 Media kernel: hdd: max request size: 512KiB Dec 19 22:02:13 Media kernel: hdd: 976773168 sectors (500107 MB) w/16384KiB Cache, CHS=60801/255/63 Dec 19 22:02:13 Media kernel: hdd: cache flushes supported Dec 19 22:02:13 Media kernel: hdd: hdd1 Dec 19 22:02:13 Media logger: /etc/rc.d/rc.inet1: /sbin/ifconfig lo 127.0.0.1 Dec 19 22:02:13 Media logger: /etc/rc.d/rc.inet1: /sbin/route add -net 127.0.0.0 netmask 255.0.0.0 lo Dec 19 22:02:13 Media ifplugd(eth0)[1307]: ifplugd 0.28 initializing. Dec 19 22:02:13 Media ifplugd(eth0)[1307]: Using interface eth0/6C:F0:49:13:40:46 with driver <r8169> (version: 2.3LK-NAPI) Dec 19 22:02:13 Media ifplugd(eth0)[1307]: Using detection mode: SIOCETHTOOL Dec 19 22:02:13 Media ifplugd(eth0)[1307]: Initialization complete, link beat detected. Dec 19 22:02:13 Media kernel: r8169: eth0: link up Dec 19 22:02:13 Media kernel: r8169: eth0: link up Dec 19 22:02:13 Media ifplugd(eth0)[1307]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 19 22:02:13 Media ifplugd(eth0)[1307]: client: Polling for DHCP server on interface eth0: Dec 19 22:02:14 Media logger: /etc/rc.d/rc.inet1: /sbin/dhcpcd -d -t 30 -h Media eth0 Dec 19 22:02:14 Media kernel: r8169: eth0: link up Dec 19 22:02:14 Media dhcpcd[1335]: broadcasting DHCP_DISCOVER Dec 19 22:02:14 Media dhcpcd[1335]: broadcastAddr option is missing in DHCP server response. Assuming 192.168.2.255 Dec 19 22:02:14 Media dhcpcd[1335]: dhcpIPaddrLeaseTime=86400 in DHCP server response. Dec 19 22:02:14 Media dhcpcd[1335]: dhcpT1value is missing in DHCP server response. Assuming 43200 sec Dec 19 22:02:14 Media dhcpcd[1335]: dhcpT2value is missing in DHCP server response. Assuming 75600 sec Dec 19 22:02:14 Media dhcpcd[1335]: DHCP_OFFER received from (192.168.2.1) Dec 19 22:02:14 Media dhcpcd[1335]: broadcasting DHCP_REQUEST for 192.168.2.102 Dec 19 22:02:14 Media dhcpcd[1335]: dhcpIPaddrLeaseTime=86400 in DHCP server response. Dec 19 22:02:14 Media dhcpcd[1335]: dhcpT1value is missing in DHCP server response. Assuming 43200 sec Dec 19 22:02:14 Media dhcpcd[1335]: dhcpT2value is missing in DHCP server response. Assuming 75600 sec Dec 19 22:02:14 Media dhcpcd[1335]: DHCP_ACK received from (192.168.2.1) ...
mr-hexen Posted December 20, 2011 Posted December 20, 2011 looks like some I/O errors on ATA4. Check the cables, maybe one came loose while moving the server around (check power and sata)
mr-hexen Posted December 20, 2011 Posted December 20, 2011 this post seems very similar as well... http://lime-technology.com/forum/index.php?topic=17415.0
dgaschk Posted December 20, 2011 Posted December 20, 2011 See here: http://lime-technology.com/wiki/index.php?title=The_Analysis_of_Drive_Issues#Drive_Interface_Issues This indicates a SATA cable issue.
Chris Posted December 21, 2011 Author Posted December 21, 2011 SATA cable it is! After pulling the cable out at both ends then re-inserting, the problem went away as mysteriously as it surfaced. Thanks to all who spotted the problem and pointed me in the right direction!
Recommended Posts
Archived
This topic is now archived and is closed to further replies.