- Subscribe to RSS Feed
- Mark Topic as New
- Mark Topic as Read
- Float this Topic for Current User
- Bookmark
- Subscribe
- Printer Friendly Page
readynas nv+ v1 stuck on resync, gui null/nonresponsive
- Mark as New
- Bookmark
- Subscribe
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
readynas nv+ v1 stuck on resync, gui null/nonresponsive
Background: 4 drives. 1tb used out of 3tb
4.1.16 was applied in early Nov. but never rebooted.
Disks hadn't displayed any errors, except 1 disk was slowly incrementing bad sector count, around 1/month.
Had stopped getting any email alerts after a firmware upgrade, with email alert config error of "can't reach smtp" server.
Issue history
Unit was nonresponsive to power button or ping last week. So power cycled. Hung on boot with "bad disk." Pulled disks to get it to boot. Pulling disk 3 allowed boot.
Downloaded all logs from GUI.
Used power button hold for 5 sec, to force firmware reinstall.
Reinserted disk 3, seemed on, but then disk 4 would not show up. Booted unit without disk 4.
Inserted disk 4 11am sun, and front display reported resync with eta of 17 hrs. raidar sees unit but shows resync status of 0%, and has not changed status as of mon am. display on unit still showing same 1tb used out of 3.
gui is nonresponsive now.
"Dec 3 11:45:35 infrant RAIDiator: RAID event detected (infrant) : Disk initialization started. The estimated time of completion is 17 hour(s) and 39 minute(s), at which time you will be notified via email. You can also check the progress in Frontview in the Volumes -> RAID Settings tab. Please do not shutdown the system while the initialization is in progress.\n\n[Sun Dec 3 11:45:27 PST 2017]
Dec 3 11:45:36 infrant kernel: ::::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:45:36 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:45:36 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:45:36 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (sfdisk)"
Suggestions? Next steps?
I'd like to get a backup, priority 1.
- Mark as New
- Bookmark
- Subscribe
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
Re: readynas nv+ v1 stuck on resync, gui null/nonresponsive
Dec 3 11:44:18 infrant kernel: :::::::::::::==== SATA init channel 3
Dec 3 11:44:18 infrant kernel: Fail to establish communications with device !!
Dec 3 11:44:18 infrant kernel: Unstable signal detected: --> SATA Hot remove event on chn 3, 42000/60007384
Dec 3 11:44:18 infrant kernel: hwif->irq = 35, remove 1
Dec 3 11:44:18 infrant kernel: sata_hotplug: /sbin/hotplug remove hdiUser mode helper start.
Dec 3 11:44:18 infrant kernel: done do_sata_hotplug
Dec 3 11:44:22 infrant RAIDiator: Hotplug disk event detected (infrant) : Disk remove event occurred on SATA channel 4.\n\n[Sun Dec 3 11:44:20 PST 2017]
Dec 3 11:44:23 infrant kernel: Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:44:23 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:44:23 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:44:23 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
Dec 3 11:44:23 infrant kernel: Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:44:23 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:44:23 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:44:23 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
Dec 3 11:44:29 infrant kernel: ::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:44:29 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:44:29 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:44:29 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (sfdisk)
Dec 3 11:44:32 infrant RAIDiator: Disk failure - automatic shutdown in 30 minutes (infrant) : The disk on channel 4 has failed. The NAS will automatically shutdown in 30 minutes to prevent possible data loss resulting from additional failed drives.\n\n[Sun Dec 3 11:44:29 PST 2017]
Dec 3 11:44:52 infrant kernel: ::==== SATA init channel 3
Dec 3 11:44:52 infrant kernel: lost 0x113 status from SATA interface.
Dec 3 11:44:52 infrant kernel: After INIT SATA channel 3, retry=30000, sata=1, status=d0
Dec 3 11:44:52 infrant kernel: Fail to get ready statu from drive.
Dec 3 11:44:52 infrant kernel: Unstable signal detected: --> SATA Hot remove event on chn 3, 42000/60007384
Dec 3 11:44:52 infrant kernel: hwif->irq = 35, remove 1
Dec 3 11:44:52 infrant kernel: sata_hotplug: /sbin/hotplug remove hdiUser mode helper start.
Dec 3 11:44:52 infrant kernel: done do_sata_hotplug
Dec 3 11:44:54 infrant RAIDiator: Hotplug disk event detected (infrant) : Disk remove event occurred on SATA channel 4.\n\n[Sun Dec 3 11:44:53 PST 2017]
Dec 3 11:44:54 infrant kernel: Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:44:54 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:44:54 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:44:54 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
Dec 3 11:45:01 infrant kernel: ::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:45:01 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:45:01 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:45:01 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (sfdisk)
Dec 3 11:45:23 infrant kernel: ::==== SATA init channel 3
Dec 3 11:45:23 infrant kernel: After INIT SATA channel 3, retry=10929, sata=113, status=50
Dec 3 11:45:23 infrant kernel: --> SATA Hot add event on chn 3, 63104/60007384
Dec 3 11:45:23 infrant kernel: hwif->irq = 35, add 1
Dec 3 11:45:23 infrant kernel: XRAID on, should re-scan the drive after drive inited.
Dec 3 11:45:23 infrant kernel: After re-id drive, drive_failure = 0
Dec 3 11:45:23 infrant kernel: sata_hotplug: /sbin/hotplug add hdiUser mode helper start.
Dec 3 11:45:23 infrant kernel: done do_sata_hotplug
Dec 3 11:45:25 infrant RAIDiator: Hotplug disk event detected (infrant) : Disk add event occurred on SATA channel 4.\n\n[Sun Dec 3 11:45:23 PST 2017]
Dec 3 11:45:25 infrant kernel: X_RAID_ADD_NO_INIT 3
Dec 3 11:45:25 infrant kernel: Doing re_init_drive_status for XRAID real values.
Dec 3 11:45:25 infrant kernel: update_drive_id on hdi
Dec 3 11:45:25 infrant kernel: No TLER on ST2000DM001-1ER164 ^P\200@
Dec 3 11:45:25 infrant kernel: hdi: ST2000DM001-1ER164 (s/n:Z4Z1HEYR), ATA DISK drive (ATAEXT)
Dec 3 11:45:25 infrant kernel: hdi: max request size: 512KiB
Dec 3 11:45:25 infrant kernel: hdi: use capacity 3907029168 sectors (2000398 MB)
Dec 3 11:45:25 infrant kernel: Drive support hpa, still should not change max addr.
Dec 3 11:45:25 infrant kernel: :<6>hdi: 3907008688 sectors (2000388 MB), CHS=65535/255/63
Dec 3 11:45:25 infrant kernel: :new mode = ST2000DM001-1ER164, new size = 3907008688, old size = 2930256686
Dec 3 11:45:25 infrant kernel: Adding missing drive in 4, add=1 morethanproc=1, disk=1
Dec 3 11:45:25 infrant kernel: Dump hwif 8041efb8 structure, 0-8041d398
Dec 3 11:45:25 infrant kernel: 1-8041daa0|1-8041e1a8|1-8041e8b0|1-8041efb8
Dec 3 11:45:25 infrant kernel: 0-8041f6c0|0-8041fdc8|0-804204d0|1-80420bd8
Dec 3 11:45:25 infrant kernel: hwif->name---------------------ide4
Dec 3 11:45:26 infrant kernel: hwif->hwgroup------------------81f59b40
Dec 3 11:45:26 infrant kernel: hwif->irq----------------------35
Dec 3 11:45:26 infrant kernel: hwif->present------------------1
Dec 3 11:45:26 infrant kernel: hwif->hold---------------------1
Dec 3 11:45:26 infrant kernel: hwif->noprobe^I^I^I0
Dec 3 11:45:26 infrant kernel: hwif->true_device^I^I0
Dec 3 11:45:26 infrant kernel: hwif->state0^I^I^I0
Dec 3 11:45:26 infrant kernel: hwif->drives[0].name----------hdi
Dec 3 11:45:26 infrant kernel: hwif->drives[0].present-------1
Dec 3 11:45:26 infrant kernel: hwif->drives[0].id_read-------1
Dec 3 11:45:26 infrant kernel: hwif->drives[0].noprobe^I0
Dec 3 11:45:26 infrant kernel: hwif->drives[0].dead^I^I0
Dec 3 11:45:26 infrant kernel: hwif->drives[0].id^I^I81f5a940
Dec 3 11:45:26 infrant kernel: hwif->drives[1].present-------0
Dec 3 11:45:26 infrant kernel: hwif->drives[1].id_read-------0
Dec 3 11:45:26 infrant kernel: ATA DISK drive 8041f050
Dec 3 11:45:26 infrant kernel: hdi: ST2000DM001-1ER164, hdi: enable ATAEXT
Dec 3 11:45:26 infrant kernel: Dump hwif 8041efb8 structure, 0-8041d398
Dec 3 11:45:26 infrant kernel: 1-8041daa0|1-8041e1a8|1-8041e8b0|1-8041efb8
Dec 3 11:45:26 infrant kernel: 0-8041f6c0|0-8041fdc8|0-804204d0|1-80420bd8
Dec 3 11:45:26 infrant kernel: hwif->name---------------------ide4
Dec 3 11:45:26 infrant kernel: hwif->hwgroup------------------81f59b40
Dec 3 11:45:26 infrant kernel: hwif->irq----------------------35
Dec 3 11:45:26 infrant kernel: hwif->present------------------1
Dec 3 11:45:26 infrant kernel: hwif->hold---------------------1
Dec 3 11:45:26 infrant kernel: hwif->noprobe^I^I^I0
Dec 3 11:45:26 infrant kernel: hwif->true_device^I^I0
Dec 3 11:45:26 infrant kernel: hwif->state0^I^I^I0
Dec 3 11:45:26 infrant kernel: hwif->drives[0].name----------hdi
Dec 3 11:45:26 infrant kernel: hwif->drives[0].present-------1
Dec 3 11:45:26 infrant kernel: hwif->drives[0].id_read-------1
Dec 3 11:45:26 infrant kernel: hwif->drives[0].noprobe^I0
Dec 3 11:45:26 infrant kernel: hwif->drives[0].dead^I^I1
Dec 3 11:45:26 infrant kernel: hwif->drives[0].id^I^I81f5a940
Dec 3 11:45:26 infrant kernel: hwif->drives[1].present-------0
Dec 3 11:45:26 infrant kernel: hwif->drives[1].id_read-------0
Dec 3 11:45:26 infrant kernel: Add dead device for only disk, true device set to 0.
Dec 3 11:45:26 infrant kernel: idedisk_deaddisk_init on hdi
Dec 3 11:45:26 infrant kernel: ide-disk: hdi: from special init, need to reset values.
Dec 3 11:45:26 infrant kernel: hdi: max request size: 512KiB
Dec 3 11:45:26 infrant kernel: hdi: use capacity 3907029168 sectors (2000398 MB)
Dec 3 11:45:26 infrant kernel: Drive support hpa, still should not change max addr.
Dec 3 11:45:26 infrant kernel: :<6>hdi: 3907008688 sectors (2000388 MB), CHS=65535/255/63
Dec 3 11:45:26 infrant kernel: Drive need to be inited, will add after initialization.
Dec 3 11:45:26 infrant kernel: Disk number= 1: true=1, d=8041db38
Dec 3 11:45:26 infrant kernel: Disk number= 1, true device.
Dec 3 11:45:26 infrant kernel: Disk number= 2: true=1, d=8041e240
Dec 3 11:45:26 infrant kernel: Disk number= 2, true device.
Dec 3 11:45:26 infrant kernel: Disk number= 3: true=1, d=8041e948
Dec 3 11:45:26 infrant kernel: Disk number= 3, true device.
Dec 3 11:45:26 infrant kernel: Disk number= 4: true=0, d=8041f050
Dec 3 11:45:26 infrant kernel: life_chn=7, new_chn=0
Dec 3 11:45:26 infrant kernel: No free drive to add for (init/expand) on.
Dec 3 11:45:26 infrant kernel: Init drives on 8
Dec 3 11:45:26 infrant kernel: X_RAID command failed
Dec 3 11:45:26 infrant kernel: ::Prepare drive hdi, size=3907008688
Dec 3 11:45:26 infrant kernel: Sending init start event.
Dec 3 11:45:26 infrant kernel: Select r_model ID at 3(4), ST2000DM001-1ER164, CC25
Dec 3 11:45:26 infrant kernel: New drive size checking: 3907008688 / 2930256688.
Dec 3 11:45:26 infrant kernel: Checking BUSY....
Dec 3 11:45:26 infrant kernel: Send SECURITY SET PASSWORD command
Dec 3 11:45:26 infrant kernel: Wait completion
Dec 3 11:45:26 infrant kernel: Security Set Password command succeed
Dec 3 11:45:26 infrant kernel: Checking BUSY....
Dec 3 11:45:26 infrant kernel: Send SECURITY SET PASSWORD command
Dec 3 11:45:26 infrant kernel: Wait completion
Dec 3 11:45:26 infrant kernel: Security Set Password command succeed
Dec 3 11:45:26 infrant kernel: Send SECURITY ERASE UNIT command
Dec 3 11:45:26 infrant kernel: Take drive out from channel 3, block access to it.
Dec 3 11:45:26 infrant kernel: sata_hotplug: /sbin/hotplug xraid_disk_init_started hdiUser mode helper start.
Dec 3 11:45:26 infrant kernel: done do_sata_hotplug
Dec 3 11:45:27 infrant kernel: :<6>hdc: cache flushes supported
Dec 3 11:45:27 infrant kernel: :<6>hde: cache flushes supported
Dec 3 11:45:27 infrant kernel: :<6>hdg: cache flushes supported
Dec 3 11:45:30 infrant kernel: ::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:45:30 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:45:30 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:45:30 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (sfdisk)
Dec 3 11:45:35 infrant RAIDiator: RAID event detected (infrant) : Disk initialization started. The estimated time of completion is 17 hour(s) and 39 minute(s), at which time you will be notified via email. You can also check the progress in Frontview in the Volumes -> RAID Settings tab. Please do not shutdown the system while the initialization is in progress.\n\n[Sun Dec 3 11:45:27 PST 2017]
Dec 3 11:45:36 infrant kernel: ::::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:45:36 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:45:36 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:45:36 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (sfdisk)
Dec 3 11:46:47 infrant kernel: ::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:46:47 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:46:47 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:46:47 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
Dec 3 11:46:47 infrant kernel: Out of XRAID area, block=3907008672, sectors=16
Dec 3 11:46:47 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 11:46:47 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 11:46:47 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
Dec 3 12:00:39 infrant ntpdate[4411]: step time server 45.33.48.4 offset 37.613425 sec
Dec 3 13:01:34 infrant kernel: ::::::::::::::::::::::::::::::::::::::::::::::::::::Out of XRAID area, block=3907008672, sectors=16
Dec 3 13:01:34 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 13:01:34 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 13:01:34 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
Dec 3 13:01:34 infrant kernel: Out of XRAID area, block=3907008672, sectors=16
Dec 3 13:01:34 infrant kernel: Device not exist, return NO_TOUCH.
Dec 3 13:01:34 infrant kernel: Cannot access channel 3, block rq (3907008672/16).
Dec 3 13:01:34 infrant kernel: Buffer I/O error on device hdi, logical block 244188042 (fdisk)
- Mark as New
- Bookmark
- Subscribe
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
Re: readynas nv+ v1 stuck on resync, gui null/nonresponsive
I suspect disk 3 failed - did you test it before you reinserted it into the array?
FWIW, it would have been better to make the backup immediately when the NAS booted.
It was a mistake to resync disk 4, and if disk 3 isn't healthy that might have cost you your data.
- Mark as New
- Bookmark
- Subscribe
- Subscribe to RSS Feed
- Permalink
- Report Inappropriate Content
Re: readynas nv+ v1 stuck on resync, gui null/nonresponsive
well after a couple of hung boots, managed to get the unit up and running.
Enabled ssh access
Fixed the email error, SMTP server can't be reached. email log showed auth failure from gmail, so disabled 2fa and allowed weak apps.
Deleted a 300+ MB leafp2p log, and reduced boot volume usage to 25%.
Disk 4 is back up, and all 4 disks are up and running:
RAIDiator: RAID event detected (infrant) : Disk initialization successfully finished.
RAIDiator: RAID event detected (infrant) : RAID sync finished on volume C. The volume is now fully redundant.
Backup volume in progress... before anything else....