NETGEAR is aware of a growing number of phone and online scams. To learn how to stay safe click here.
Forum Discussion
Vitruvius
Oct 21, 2012Aspirant
ReadyNas Pro Expansion maybe failed?
I recently tried to expand my ReadyNas and after a lot waiting, it's recognising the additional disc, saying it's allocated some space - BUT - there's been no increase in the total storage amount.
Am I missing something?
Here's what happened:
I have a ReadyNas Pro Business which up until recently had 5 discs in it:
3 x 1Tb WDC drives
2 x 2Tb WDC drives
This gave me 4.6Tb of redundant space (X-RAID2)
RAIDiator 4.2.19-T4
Over the weekend I added a single 3Tb drive to the final slot. Expansion began, here's what happened:
1!!Fri Oct 19 13:29:41 EST 2012!!root!!Volume expansion or migration started. Do not interrupt the system during this time. When finished, email notification will be sent to the alert contact list.
1!!Fri Oct 19 15:15:25 EST 2012!!root!!Volume scan found and corrected errors.
0!!Fri Oct 19 15:15:45 EST 2012!!root!!System is up.
1!!Fri Oct 19 17:51:30 EST 2012!!root!!Volume scan found and corrected errors.
0!!Fri Oct 19 17:51:50 EST 2012!!root!!System is up.
1!!Sat Oct 20 00:00:38 EST 2012!!root!!Continuing with volume expansion or migration. Do not interrupt the system during this time. When complete, email notification will be sent to the alert contact list.
1!!Sat Oct 20 00:01:23 EST 2012!!root!!Please deselect the 'Disable journaling' option in the FrontView System/Performance tab to continue with the online volume expansion. Note that you will need to reboot the ReadyNAS to enable journaling.
I disabled Journaling as requested
1!!Sat Oct 20 06:42:08 EST 2012!!root!!Volume scan found and corrected errors.
0!!Sat Oct 20 06:42:21 EST 2012!!root!!System is up.
And that's where we are at:
3100 GB (66%) of 4630 GB used
Configuration: RAID Level X-RAID2, 6 disks
Status: Redundant
RAID Disks:
Ch 1 : WDC WD1002FBYS-02A6B0 [931 GB] 927 GB allocated
Ch 2 : WDC WD1002FBYS-02A6B0 [931 GB] 927 GB allocated
Ch 3 : WDC WD1002FBYS-02A6B0 [931 GB] 927 GB allocated
Ch 4 : WDC WD2003FYYS-02W0B0 [1863 GB] 1858 GB allocated
Ch 5 : WDC WD2003FYYS-02W0B0 [1863 GB] 1858 GB allocated
Ch 6 : Seagate ST33000650NS [2794 GB] 1858 GB allocated
So no expansion of storage despite Ch6 recognising the disc and say '1858Gb allocated' - what's going on? Still 4.6Tb...
Can anyone help?
Thanks
Am I missing something?
Here's what happened:
I have a ReadyNas Pro Business which up until recently had 5 discs in it:
3 x 1Tb WDC drives
2 x 2Tb WDC drives
This gave me 4.6Tb of redundant space (X-RAID2)
RAIDiator 4.2.19-T4
Over the weekend I added a single 3Tb drive to the final slot. Expansion began, here's what happened:
1!!Fri Oct 19 13:29:41 EST 2012!!root!!Volume expansion or migration started. Do not interrupt the system during this time. When finished, email notification will be sent to the alert contact list.
1!!Fri Oct 19 15:15:25 EST 2012!!root!!Volume scan found and corrected errors.
0!!Fri Oct 19 15:15:45 EST 2012!!root!!System is up.
1!!Fri Oct 19 17:51:30 EST 2012!!root!!Volume scan found and corrected errors.
0!!Fri Oct 19 17:51:50 EST 2012!!root!!System is up.
1!!Sat Oct 20 00:00:38 EST 2012!!root!!Continuing with volume expansion or migration. Do not interrupt the system during this time. When complete, email notification will be sent to the alert contact list.
1!!Sat Oct 20 00:01:23 EST 2012!!root!!Please deselect the 'Disable journaling' option in the FrontView System/Performance tab to continue with the online volume expansion. Note that you will need to reboot the ReadyNAS to enable journaling.
I disabled Journaling as requested
1!!Sat Oct 20 06:42:08 EST 2012!!root!!Volume scan found and corrected errors.
0!!Sat Oct 20 06:42:21 EST 2012!!root!!System is up.
And that's where we are at:
3100 GB (66%) of 4630 GB used
Configuration: RAID Level X-RAID2, 6 disks
Status: Redundant
RAID Disks:
Ch 1 : WDC WD1002FBYS-02A6B0 [931 GB] 927 GB allocated
Ch 2 : WDC WD1002FBYS-02A6B0 [931 GB] 927 GB allocated
Ch 3 : WDC WD1002FBYS-02A6B0 [931 GB] 927 GB allocated
Ch 4 : WDC WD2003FYYS-02W0B0 [1863 GB] 1858 GB allocated
Ch 5 : WDC WD2003FYYS-02W0B0 [1863 GB] 1858 GB allocated
Ch 6 : Seagate ST33000650NS [2794 GB] 1858 GB allocated
So no expansion of storage despite Ch6 recognising the disc and say '1858Gb allocated' - what's going on? Still 4.6Tb...
Can anyone help?
Thanks
6 Replies
Replies have been turned off for this discussion
- mdgm-ntgrNETGEAR Employee RetiredCan you try one more reboot?
- VitruviusAspirantJust tried that - no luck.
I did however notice in the logs that the expansion seemed to take a break on Friday and wait until midnight to continue:
0!!Fri Oct 19 17:51:50 EST 2012!!root!!System is up.
1!!Sat Oct 20 00:00:38 EST 2012!!root!!Continuing with volume expansion or migration.
Maybe I'll come in tomorrow and it will have expanded overnight... - mdgm-ntgrNETGEAR Employee RetiredAnother point worth noting is that you're still running a beta firmware release over a year after the production release of that firmware.
If you haven't got any signs of expansion happening/finished by tomorrow you probably should contact tech support but they might not want to help as you're still running beta firmware. - VitruviusAspirantYes - good advice indeed.
I'll wait til tomorrow and see if it hasn't expanded before I do anything - perhaps an upgrade to the latest firmware would be the first option. - rickc1AspirantI have a similar problem. I have a ReadyNas Ultra6 running 4.2.22 software. I initially launched it with 4 3T seagate drives, and then recently added 2 3T drives. I plugged the new drives in, with the unit running, nearly simultaneously. The unit started chugging away, and got the fifth drive up and running, and started on the 6th, and then it froze (while striping). I rebooted the unit, and it finished striping and started volume expansion. That also froze sometime in the process, so I rebooted. It finally finished, but said that volume expansion had failed.
this is the system log, somewhat arbitrarily from nov 15th. you can see a pattern of repeated attempts
Nov 15 13:12:05 NAS03 kernel: md: reshape of RAID array md2
Nov 15 13:12:05 NAS03 kernel: md: minimum _guaranteed_ speed: 1000 KB/sec/disk.
Nov 15 13:12:05 NAS03 kernel: md: using maximum available idle IO bandwidth (but not more than 200000 KB/sec) for reshape.
Nov 15 13:12:05 NAS03 kernel: md: using 128k window, over a total of 778060544 blocks.
Nov 15 14:20:19 NAS03 udhcpc[2911]: Sending renew...
Nov 15 14:20:19 NAS03 udhcpc[2911]: Lease of 192.168.1.115 obtained, lease time 86400
Nov 15 20:45:53 NAS03 kernel: md: md2: reshape done.
Nov 15 20:45:54 NAS03 kernel: RAID conf printout:
Nov 15 20:45:54 NAS03 kernel: --- level:5 rd:6 wd:6
Nov 15 20:45:54 NAS03 kernel: disk 0, o:1, dev:sda5
Nov 15 20:45:54 NAS03 kernel: disk 1, o:1, dev:sdb5
Nov 15 20:45:54 NAS03 kernel: disk 2, o:1, dev:sdc5
Nov 15 20:45:54 NAS03 kernel: disk 3, o:1, dev:sdd5
Nov 15 20:45:54 NAS03 kernel: disk 4, o:1, dev:sde5
Nov 15 20:45:54 NAS03 kernel: disk 5, o:1, dev:sdf5
Nov 15 20:45:54 NAS03 kernel: md2: detected capacity change from 3186935988224 to 3983669985280
Nov 15 20:45:55 NAS03 kernel: md2: unknown partition table
Nov 15 20:45:56 NAS03 kernel: md: bind<sdf6>
Nov 15 20:45:58 NAS03 kernel: RAID conf printout:
Nov 15 20:45:58 NAS03 kernel: --- level:5 rd:6 wd:6
Nov 15 20:45:58 NAS03 kernel: disk 0, o:1, dev:sda6
Nov 15 20:45:58 NAS03 kernel: disk 1, o:1, dev:sdb6
Nov 15 20:45:58 NAS03 kernel: disk 2, o:1, dev:sdc6
Nov 15 20:45:58 NAS03 kernel: disk 3, o:1, dev:sdd6
Nov 15 20:45:58 NAS03 kernel: disk 4, o:1, dev:sde6
Nov 15 20:45:58 NAS03 kernel: disk 5, o:1, dev:sdf6
Nov 15 20:45:58 NAS03 kernel: md: reshape of RAID array md3
Nov 15 20:45:58 NAS03 kernel: md: minimum _guaranteed_ speed: 100000 KB/sec/disk.
Nov 15 20:45:58 NAS03 kernel: md: using maximum available idle IO bandwidth (but not more than 200000 KB/sec) for reshape.
Nov 15 20:45:58 NAS03 kernel: md: using 128k window, over a total of 2147483072 blocks.
Nov 15 23:28:55 NAS03 kernel: md1: unknown partition table
Nov 16 02:20:19 NAS03 udhcpc[2911]: Sending renew...
Nov 16 02:20:19 NAS03 udhcpc[2911]: Lease of 192.168.1.115 obtained, lease time 86400
Nov 16 06:25:07 NAS03 syslogd 1.4.1#18: restart.
Nov 16 14:20:19 NAS03 udhcpc[2911]: Sending renew...
Nov 16 14:20:19 NAS03 udhcpc[2911]: Lease of 192.168.1.115 obtained, lease time 86400
Nov 16 18:33:51 NAS03 kernel: md: md3: reshape done.
Nov 16 18:33:52 NAS03 kernel: RAID conf printout:
Nov 16 18:33:52 NAS03 kernel: --- level:5 rd:6 wd:6
Nov 16 18:33:52 NAS03 kernel: disk 0, o:1, dev:sda6
Nov 16 18:33:52 NAS03 kernel: disk 1, o:1, dev:sdb6
Nov 16 18:33:52 NAS03 kernel: disk 2, o:1, dev:sdc6
Nov 16 18:33:52 NAS03 kernel: disk 3, o:1, dev:sdd6
Nov 16 18:33:52 NAS03 kernel: disk 4, o:1, dev:sde6
Nov 16 18:33:52 NAS03 kernel: disk 5, o:1, dev:sdf6
Nov 16 18:33:52 NAS03 kernel: md3: detected capacity change from 8796090662912 to 10995113328640
Nov 16 18:33:57 NAS03 kernel: md3: unknown partition table
Nov 16 18:34:05 NAS03 kernel: do EXT4_IOC_RESIZE_FS
Nov 16 18:34:05 NAS03 kernel: EXT4-fs warning (device dm-0): ext4_resize_fs:1675: No reserved GDT blocks, can't resize
Nov 17 02:20:19 NAS03 udhcpc[2911]: Sending renew...
Nov 17 02:20:20 NAS03 udhcpc[2911]: Lease of 192.168.1.115 obtained, lease time 86400
Nov 17 06:25:03 NAS03 syslogd 1.4.1#18: restart.
and the expansion log, from roughly the same time on the 15th:
[2012/11/15 13:12:04 4571] LINE 448: exec command: echo "DOING_SHAPE_MD" > /.os_V_E_continue
[2012/11/15 13:12:04 4571] lv:5 raid_disk:5 xraid:1 md_id:2 lv_chg:0
[2012/11/15 13:12:04 4571] LINE 5883: exec command: mdadm -G /dev/md2 --raid-disks=6
mdadm: Need to backup 1280K of critical section..
[2012/11/15 13:12:04 4571] LINE 3412: exec command: tune2fs -Estride=16,stripe_width=80 /dev/c/c
tune2fs 1.42.3 (14-May-2012)
Setting stride size to 16
Setting stripe width to 80
[2012/11/15 13:12:05 4571] LINE 448: exec command: echo "DOING_SHAPE_MD" > /.os_V_E_continue
[2012/11/15 13:12:08 4571] LINE 3152: exec command: echo 100000 > /sys/block/md2/md/sync_speed_min
[2012/11/15 13:12:08 4571] LINE 3155: exec command: mdadm --misc -W /dev/md2
[2012/11/15 20:45:55 4571] ADD1 pdev /dev/sdf6 to md /dev/md3, level=5, repair:=0, degraded=0, part_idx=3
[2012/11/15 20:45:55 4571] Repair: 6, 5, 5
[2012/11/15 20:45:55 4571] LINE 3338: exec command: mdadm -a /dev/md3 /dev/sdf6
mdadm: added /dev/sdf6
[2012/11/15 20:45:56 4571] ------ conditions: 0 1 0
[2012/11/15 20:45:56 4571] LINE 3365: exec command: dd if=/dev/sdf6 of=/dev/null bs=512 count=1
1+0 records in
1+0 records out
512 bytes (512 B) copied, 0.0200738 s, 25.5 kB/s
[2012/11/15 20:45:57 4571] LINE 448: exec command: echo "DOING_SHAPE_MD" > /.os_V_E_continue
[2012/11/15 20:45:57 4571] lv:5 raid_disk:5 xraid:1 md_id:3 lv_chg:0
[2012/11/15 20:45:57 4571] LINE 5883: exec command: mdadm -G /dev/md3 --raid-disks=6
mdadm: Need to backup 1280K of critical section..
[2012/11/15 20:45:57 4571] LINE 448: exec command: echo "DOING_SHAPE_MD" > /.os_V_E_continue
[2012/11/15 20:46:01 4571] LINE 3152: exec command: echo 100000 > /sys/block/md3/md/sync_speed_min
[2012/11/15 20:46:02 4571] LINE 3155: exec command: mdadm --misc -W /dev/md3
[2012/11/16 18:33:52 4571] LINE 3455: exec command: mdadm --misc -W /dev/md* /dev/md* /dev/md* /dev/md*
[2012/11/16 18:33:54 4571] md grow ok by /dev/sdf
[2012/11/16 18:33:54 4571] New 1 partitions added, 0 failed.
[2012/11/16 18:33:56 4571] LINE 4162: exec command: vgdisplay -c c > /var/log/frontview/.V_E.vgdisplayout ; lvdisplay -c c > /var/log/frontview/.V_E.lvdisplayout
[2012/11/16 18:33:57 4571] 0 vg= c lv= /dev/c/c
[2012/11/16 18:33:57 4571] 1 vg=r/w lv=c
[2012/11/16 18:33:57 4571] 2 vg=772 lv=3
[2012/11/16 18:33:57 4571] 3 vg=-1 lv=1
[2012/11/16 18:33:57 4571] 4 vg=0 lv=-1
[2012/11/16 18:33:57 4571] 5 vg=1 lv=1
[2012/11/16 18:33:57 4571] 6 vg=1 lv=23393730560
[2012/11/16 18:33:57 4571] 7 vg=-1 lv=178480
[2012/11/16 18:33:57 4571] 8 vg=0 lv=-1
[2012/11/16 18:33:57 4571] 9 vg=2 lv=0
[2012/11/16 18:33:57 4571] 10 vg=2 lv=0
[2012/11/16 18:33:57 4571] 11 vg=11702108160 lv=253
[2012/11/16 18:33:57 4571] 12 vg=65536 lv=0
[2012/11/16 18:33:57 4571] 13 vg=178560 lv=13
[2012/11/16 18:33:57 4571] 14 vg=178480 lv=14
[2012/11/16 18:33:57 4571] 15 vg=80 lv=15
[2012/11/16 18:33:57 4571] 16 vg=y8Dt26-hJTW-bPc2-lu8h-56MD-jo3E-AkWVAz
lv=16
[2012/11/16 18:33:57 4571] 17 vg=17 lv=17
[2012/11/16 18:33:57 4571] 18 vg=18 lv=18
[2012/11/16 18:33:57 4571] 19 vg=19 lv=19
[2012/11/16 18:33:57 4571] LINE 4542: exec command: pvresize /dev/md2
Physical volume "/dev/md2" changed
1 physical volume(s) resized / 0 physical volume(s) not resized
[2012/11/16 18:33:58 4571] LINE 4542: exec command: pvresize /dev/md3
Physical volume "/dev/md3" changed
1 physical volume(s) resized / 0 physical volume(s) not resized
[2012/11/16 18:33:59 4571] LINE 4162: exec command: vgdisplay -c c > /var/log/frontview/.V_E.vgdisplayout ; lvdisplay -c c > /var/log/frontview/.V_E.lvdisplayout
[2012/11/16 18:34:00 4571] 0 vg= c lv= /dev/c/c
[2012/11/16 18:34:00 4571] 1 vg=r/w lv=c
[2012/11/16 18:34:00 4571] 2 vg=772 lv=3
[2012/11/16 18:34:00 4571] 3 vg=-1 lv=1
[2012/11/16 18:34:00 4571] 4 vg=0 lv=-1
[2012/11/16 18:34:00 4571] 5 vg=1 lv=1
[2012/11/16 18:34:00 4571] 6 vg=1 lv=23393730560
[2012/11/16 18:34:00 4571] 7 vg=-1 lv=178480
[2012/11/16 18:34:00 4571] 8 vg=0 lv=-1
[2012/11/16 18:34:00 4571] 9 vg=2 lv=0
[2012/11/16 18:34:00 4571] 10 vg=2 lv=0
[2012/11/16 18:34:00 4571] 11 vg=14627635200 lv=253
[2012/11/16 18:34:00 4571] 12 vg=65536 lv=0
[2012/11/16 18:34:00 4571] 13 vg=223200 lv=13
[2012/11/16 18:34:00 4571] 14 vg=178480 lv=14
[2012/11/16 18:34:00 4571] 15 vg=44720 lv=15
[2012/11/16 18:34:00 4571] 16 vg=y8Dt26-hJTW-bPc2-lu8h-56MD-jo3E-AkWVAz
lv=16
[2012/11/16 18:34:00 4571] 17 vg=17 lv=17
[2012/11/16 18:34:00 4571] 18 vg=18 lv=18
[2012/11/16 18:34:00 4571] 19 vg=19 lv=19
[2012/11/16 18:34:00 4571] Facts:vg_total=223200,vg_alloc=178480,vg_free=44720,lv_new=44640,lv_total=%
[2012/11/16 18:34:00 4571] LINE 4374: exec command: lvextend /dev/c/c -l +44640
Extending logical volume c to 13.62 TB
Logical volume c successfully resized
[2012/11/16 18:34:03 4571] LINE 4555: exec command: /frontview/bin/volumescan &
[2012/11/16 18:34:04 4571] LINE 448: exec command: echo "DOING_F_RESIZE" > /.os_V_E_continue
[2012/11/16 18:34:05 4571] LINE 4249: exec command: resize2fs -pf /dev/c/c
resize2fs 1.42.3 (14-May-2012)
resize2fs: Permission denied to resize filesystem
Filesystem at /dev/c/c is mounted on /c; on-line resizing required
old_desc_blocks = 1046, new_desc_blocks = 1744
[2012/11/16 18:34:05 4571] Expand second phase error 10: resize2fs, err=0x100
[2012/11/16 18:34:09 4571] Volume expand failed, no drive added
[2012/11/16 18:34:09 4571] Failed to expand LVM
[2012/11/16 18:34:09 4571] STAGE_WIPE: Clean my_pid 4571
so it looks like nothing further has happened. I don't know if the 6th drive is online and working. In Radair, all six drives show green. The Volume Settings Page shows:
Configuration: RAID Level X-RAID2, 6 disks
Status: Redundant
RAID Disks:
Ch 1 : Seagate ST3000DM001-9YN166 [2794 GB]
2790 GB allocated
Ch 2 : Seagate ST3000DM001-9YN166 [2794 GB]
2790 GB allocated
Ch 3 : Seagate ST3000DM001-9YN166 [2794 GB]
2790 GB allocated
Ch 4 : Seagate ST3000DM001-9YN166 [2794 GB]
2790 GB allocated
Ch 5 : Seagate ST3000DM001-1CH166 [2794 GB]
2790 GB allocated
Ch 6 : Seagate ST3000DM001-1CH166 [2794 GB]
2790 GB allocated
So, where do I stand? - StephenBGuru - Experienced UserHow large is the C volume? When you click on the green icon for drives 5 and 6 (under disk health do you see any concerning SMART stats?
Related Content
NETGEAR Academy
Boost your skills with the Netgear Academy - Get trained, certified and stay ahead with the latest Netgear technology!
Join Us!