NETGEAR is aware of a growing number of phone and online scams. To learn how to stay safe click here.

Forum Discussion

peachums's avatar
peachums
Aspirant
Oct 23, 2015

ReadyNAS RN20400 Spindown issues, spindown cancelled

I hope someone can help me here before I send this otherwise great NAS back to the supplier.

 

One big draw for the RN204 was the disk spindown and it just doesnt work. Well I say it doesnt work, it does for a few seconds before the spindown is cancelled.

 

Here's some information on the setup.

 

Model: ReadyNAS RN204

Firmware: 6.4.0
Ethernet: eth0 (DHCP ipv4, DHCP ipv6)
Disk Info: X-RAID (Raid 5) 4 x 2TB disks
sda = ST2000DM001-9YN164 (7200rpm)
sdb = ST2000DM001-9YN164 (7200rpm)
sdc = ST2000DL003-9VT166 (5900rpm)
sdd = ST2000DL003-9VT166 (5900rpm)

I have done the following in an attempt to find the problem area.

1) removed all apps
2) turned off bitrot for all folders
3) never had snapshots on
4) turned off ReadyCLOUD
5) turned off ReadyNAS Remote
6) never had ReadNAS Replicate on
7) never had backups on


Heres the systemd-journal.log ...

 

Oct 23 18:01:41 stoNAS msmtpq[6776]: mail [ 1 ] [ 2015-10-19-21.59.02 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:01:41 stoNAS msmtpq[6799]: mail [ 2 ] [ 2015-10-19-21.59.15 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:01:41 stoNAS msmtpq[6822]: mail [ 3 ] [ 2015-10-19-22.28.27 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:01:41 stoNAS msmtpq[6845]: mail [ 4 ] [ 2015-10-19-22.34.33 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:01:41 stoNAS msmtpq[6868]: mail [ 5 ] [ 2015-10-19-22.34.47 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:01:41 stoNAS msmtpq[6891]: mail [ 6 ] [ 2015-10-20-08.20.41 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:01:41 stoNAS msmtpq[6914]: mail [ 7 ] [ 2015-10-21-08.40.35 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:04:01 stoNAS noflushd[1706]: Spinning down disk 1 (/dev/sda).
Oct 23 18:04:03 stoNAS noflushd[1706]: Spindown of disk 1 (/dev/sda) cancelled.
Oct 23 18:05:11 stoNAS connmand[1720]: ntp: time slew +0.001868 s
Oct 23 18:09:01 stoNAS CRON[7057]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 23 18:09:01 stoNAS CRON[7058]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -x /usr/lib/php5/sessionclean ] && [ -d /var/lib/php5 ] && /usr/lib/php5/sessionclean /var/lib/php5 $(/usr/lib/php5/maxlifetime))
Oct 23 18:09:01 stoNAS CRON[7057]: pam_unix(cron:session): session closed for user root
Oct 23 18:09:04 stoNAS noflushd[1706]: Spinning down disk 2 (/dev/sdb).
Oct 23 18:09:07 stoNAS noflushd[1706]: Spindown of disk 2 (/dev/sdb) cancelled.
Oct 23 18:11:42 stoNAS msmtpq[7151]: mail [ 1 ] [ 2015-10-19-21.59.02 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:11:42 stoNAS msmtpq[7174]: mail [ 2 ] [ 2015-10-19-21.59.15 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:11:42 stoNAS msmtpq[7197]: mail [ 3 ] [ 2015-10-19-22.28.27 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:11:42 stoNAS msmtpq[7220]: mail [ 4 ] [ 2015-10-19-22.34.33 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:11:42 stoNAS msmtpq[7243]: mail [ 5 ] [ 2015-10-19-22.34.47 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:11:42 stoNAS msmtpq[7266]: mail [ 6 ] [ 2015-10-20-08.20.41 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:11:42 stoNAS msmtpq[7290]: mail [ 7 ] [ 2015-10-21-08.40.35 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:14:17 stoNAS noflushd[1706]: Spinning down disk 1 (/dev/sda).
Oct 23 18:14:20 stoNAS noflushd[1706]: Spindown of disk 1 (/dev/sda) cancelled.
Oct 23 18:17:01 stoNAS CRON[7393]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 23 18:17:01 stoNAS CRON[7394]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 23 18:17:01 stoNAS CRON[7393]: pam_unix(cron:session): session closed for user root
Oct 23 18:19:20 stoNAS noflushd[1706]: Spinning down disk 2 (/dev/sdb).
Oct 23 18:19:22 stoNAS noflushd[1706]: Spindown of disk 2 (/dev/sdb) cancelled.
Oct 23 18:19:22 stoNAS noflushd[1706]: Spinning down disk 4 (/dev/sdd).
Oct 23 18:19:40 stoNAS noflushd[1706]: Spinning up disk 4 (/dev/sdd) after 0:00:15.
Oct 23 18:21:43 stoNAS msmtpq[7523]: mail [ 1 ] [ 2015-10-19-21.59.02 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:21:43 stoNAS msmtpq[7546]: mail [ 2 ] [ 2015-10-19-21.59.15 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:21:43 stoNAS msmtpq[7569]: mail [ 3 ] [ 2015-10-19-22.28.27 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:21:43 stoNAS msmtpq[7592]: mail [ 4 ] [ 2015-10-19-22.34.33 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:21:43 stoNAS msmtpq[7615]: mail [ 5 ] [ 2015-10-19-22.34.47 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:21:43 stoNAS msmtpq[7638]: mail [ 6 ] [ 2015-10-20-08.20.41 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:21:43 stoNAS msmtpq[7661]: mail [ 7 ] [ 2015-10-21-08.40.35 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:22:15 stoNAS connmand[1720]: ntp: time slew +0.002035 s
Oct 23 18:24:52 stoNAS noflushd[1706]: Spinning down disk 1 (/dev/sda).
Oct 23 18:24:54 stoNAS noflushd[1706]: Spindown of disk 1 (/dev/sda) cancelled.
Oct 23 18:30:05 stoNAS noflushd[1706]: Spinning down disk 1 (/dev/sda).
Oct 23 18:30:08 stoNAS noflushd[1706]: Spindown of disk 1 (/dev/sda) cancelled.
Oct 23 18:31:43 stoNAS msmtpq[7887]: mail [ 1 ] [ 2015-10-19-21.59.02 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:31:43 stoNAS msmtpq[7910]: mail [ 2 ] [ 2015-10-19-21.59.15 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:31:44 stoNAS msmtpq[7933]: mail [ 3 ] [ 2015-10-19-22.28.27 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:31:44 stoNAS msmtpq[7956]: mail [ 4 ] [ 2015-10-19-22.34.33 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:31:44 stoNAS msmtpq[7979]: mail [ 5 ] [ 2015-10-19-22.34.47 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:31:44 stoNAS msmtpq[8002]: mail [ 6 ] [ 2015-10-20-08.20.41 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:31:44 stoNAS msmtpq[8025]: mail [ 7 ] [ 2015-10-21-08.40.35 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:35:08 stoNAS noflushd[1706]: Spinning down disk 1 (/dev/sda).
Oct 23 18:35:10 stoNAS noflushd[1706]: Spindown of disk 1 (/dev/sda) cancelled.
Oct 23 18:35:10 stoNAS noflushd[1706]: Spinning down disk 2 (/dev/sdb).
Oct 23 18:35:13 stoNAS noflushd[1706]: Spinning down disk 3 (/dev/sdc).
Oct 23 18:35:21 stoNAS noflushd[1706]: Spinning up disk 2 (/dev/sdb) after 0:00:08.
Oct 23 18:35:22 stoNAS noflushd[1706]: Spinning up disk 3 (/dev/sdc) after 0:00:06.
Oct 23 18:39:01 stoNAS CRON[8183]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 23 18:39:01 stoNAS CRON[8184]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -x /usr/lib/php5/sessionclean ] && [ -d /var/lib/php5 ] && /usr/lib/php5/sessionclean /var/lib/php5 $(/usr/lib/php5/maxlifetime))
Oct 23 18:39:01 stoNAS CRON[8183]: pam_unix(cron:session): session closed for user root
Oct 23 18:39:48 stoNAS connmand[1720]: ntp: time slew +0.000809 s
Oct 23 18:40:13 stoNAS noflushd[1706]: Spinning down disk 4 (/dev/sdd).
Oct 23 18:40:15 stoNAS noflushd[1706]: Spindown of disk 4 (/dev/sdd) cancelled.
Oct 23 18:41:44 stoNAS msmtpq[8278]: mail [ 1 ] [ 2015-10-19-21.59.02 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:41:44 stoNAS msmtpq[8301]: mail [ 2 ] [ 2015-10-19-21.59.15 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:41:44 stoNAS msmtpq[8324]: mail [ 3 ] [ 2015-10-19-22.28.27 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:41:44 stoNAS msmtpq[8347]: mail [ 4 ] [ 2015-10-19-22.34.33 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:41:45 stoNAS msmtpq[8370]: mail [ 5 ] [ 2015-10-19-22.34.47 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:41:45 stoNAS msmtpq[8393]: mail [ 6 ] [ 2015-10-20-08.20.41 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:41:45 stoNAS msmtpq[8416]: mail [ 7 ] [ 2015-10-21-08.40.35 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:45:16 stoNAS noflushd[1706]: Spinning down disk 4 (/dev/sdd).
Oct 23 18:45:18 stoNAS noflushd[1706]: Spindown of disk 4 (/dev/sdd) cancelled.
Oct 23 18:50:19 stoNAS noflushd[1706]: Spinning down disk 4 (/dev/sdd).
Oct 23 18:50:21 stoNAS noflushd[1706]: Spindown of disk 4 (/dev/sdd) cancelled.
Oct 23 18:51:45 stoNAS msmtpq[8643]: mail [ 1 ] [ 2015-10-19-21.59.02 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:51:45 stoNAS msmtpq[8666]: mail [ 2 ] [ 2015-10-19-21.59.15 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:51:45 stoNAS msmtpq[8689]: mail [ 3 ] [ 2015-10-19-22.28.27 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:51:45 stoNAS msmtpq[8712]: mail [ 4 ] [ 2015-10-19-22.34.33 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:51:45 stoNAS msmtpq[8735]: mail [ 5 ] [ 2015-10-19-22.34.47 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:51:45 stoNAS msmtpq[8758]: mail [ 6 ] [ 2015-10-20-08.20.41 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:51:45 stoNAS msmtpq[8781]: mail [ 7 ] [ 2015-10-21-08.40.35 ] from queue ; send failed ; msmtp rc = 78
Oct 23 18:55:32 stoNAS noflushd[1706]: Spinning down disk 2 (/dev/sdb).
Oct 23 18:55:34 stoNAS noflushd[1706]: Spindown of disk 2 (/dev/sdb) cancelled.
Oct 23 18:56:52 stoNAS connmand[1720]: ntp: time slew +0.001503 s

 

I'm happy to provide any other information if it wiil help find the problem.

 

Thanks

 

 

Other Discussions
Performance

8 Replies

Replies have been turned off for this discussion
  • BrianL2's avatar
    BrianL2
    NETGEAR Employee Retired
    Oct 25, 2015

    Hi peachums,

     

    Welcome to the community!

     

    This seems strange. Let us check it on our end and see if we can reproduce the same problem that you've encountered. Also I would like to know if you have contacted support to create an issue with regard to this feature that won't work properly.

     

     

    Kind regards,

     

    BrianL

    NETGEAR Community Team

    • peachums's avatar
      peachums
      Aspirant
      Oct 26, 2015

      Hi BrianL,

       

      I have raised this as a support case and have also done some further testing.

       

      I put a power meter on the NAS and it seems to be running at 17W when idle and around 35W when being accessed.

       

      Does this suggest that 2 of the 4 disks are always powered up? And would this be the O/S disk + the disk required to provide RAID 5?

       

      I can understand that some O/S tasks may need to access disk from time to time when idle, but all the time would seem excessive.

       

      Maybe its nothing to do with this but thought you might find the test useful.

      • StephenB's avatar
        StephenB
        Guru - Experienced User
        Oct 26, 2015

        Every RAID-5 write requires accessing two drives (and depending on how it is done, could require accessing all four)..  

         

        Since the data is striped across the drives, a sustained read will of course require all drives to be spun up.

  • dishmagic66's avatar
    dishmagic66
    Guide
    Oct 25, 2015

    I see similar with 6.4.0 on my RN104.  Suddenly diskspindown is not working anymore. Disks wake up after a couple of seconds after gone to sleep. 

    I;m digging through te logs to see if i can find something.

    • BrianL2's avatar
      BrianL2
      NETGEAR Employee Retired
      Oct 26, 2015

      Hi dishmagic66,

       

      We are still checking this issue reported by @peachums. Could you check if you got the same disk spin log behavior under systemd-journal.log?

       

       

      Kind regards,

       

      BrianL
      NETGEAR Community Team

      • dishmagic66's avatar
        dishmagic66
        Guide
        Oct 26, 2015

        Hi BrianL,

        The behaviour is not really the same as far as  can tell. I renabled diskspindown and post a piece of systemd-journal.log

        Oct 26 20:05:33 Readynas apache_access[4079]: 192.168.2.8 "GET /admin/images/system/buttons/btn-question-w.png HTTP/1.1" 404
Oct 26 20:05:33 Readynas apache_access[4079]: 192.168.2.8 "POST /dbbroker HTTP/1.1" 200
Oct 26 20:05:37 Readynas readynasd[3036]: ResetFailedUnit: GDBus.Error:org.freedesktop.systemd1.NoSuchUnit: Unit noflushd.service is not loaded.
Oct 26 20:05:37 Readynas noflushd[18930]: Enabling spindown for disk 4 [sda,0:3:ST2000DM001-1CH164:S1E02AKK:CC29:7200]
Oct 26 20:05:37 Readynas noflushd[18930]: Enabling spindown for disk 3 [sdb,0:2:ST3000VN000-1HJ166:W7302RF3:SC60:5900]
Oct 26 20:05:37 Readynas noflushd[18930]: Enabling spindown for disk 1 [sdd,0:0:ST2000DM001-1CH164:Z1E2SHYW:CC24:7200]
Oct 26 20:05:37 Readynas noflushd[18930]: Enabling spindown for disk 2 [sdc,0:1:ST2000DM001-1CH164:Z1E2S8CP:CC24:7200]
Oct 26 20:05:37 Readynas readynasd[3036]: Automatic disk spin-down enabled.
Oct 26 20:05:38 Readynas afpd[18932]: dsi_tcp_open: invalid header
Oct 26 20:06:01 Readynas cron[2787]: (*system*spindown) RELOAD (/etc/cron.d/spindown)
Oct 26 20:06:31 Readynas systemd[1]: [/lib/systemd/system/fvapp-surveillance.service:6] Invalid executable path in command line, ignoring: mkdir -p /var/nuuo/log
Oct 26 20:06:41 Readynas systemd[1]: [/lib/systemd/system/fvapp-surveillance.service:6] Invalid executable path in command line, ignoring: mkdir -p /var/nuuo/log
Oct 26 20:07:35 Readynas systemd[1]: [/lib/systemd/system/fvapp-surveillance.service:6] Invalid executable path in command line, ignoring: mkdir -p /var/nuuo/log
Oct 26 20:07:43 Readynas dbus[2792]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Oct 26 20:07:43 Readynas snapperd[19177]: Requesting DBus name
Oct 26 20:07:43 Readynas dbus[2792]: [system] Successfully activated service 'org.opensuse.Snapper'
Oct 26 20:07:43 Readynas snapperd[19177]: Loading snapper configs
Oct 26 20:07:43 Readynas snapperd[19177]: Listening for method calls and signals
Oct 26 20:07:45 Readynas readynasd[3036]: head: cannot open `/sys/kernel/config/target/iscsi/*/tpgt*/lun/lun*/lun*/info' for reading: No such file or directory
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.LeafNets.stable_secret"
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.all.stable_secret"
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.default.stable_secret"
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.eth0.stable_secret"
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.eth1.stable_secret"
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.lo.stable_secret"
Oct 26 20:07:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.tunl0.stable_secret"
Oct 26 20:07:49 Readynas kernel: nr_pdflush_threads exported in /proc is scheduled for removal
Oct 26 20:07:52 Readynas readynasd[3036]: Segmentation fault (core dumped)
Oct 26 20:07:56 Readynas readynasd[3036]: system log /var/readynasd/system_logs.zip was created successfully!
Oct 26 20:07:56 Readynas readynasd[3036]: fread(/var/readynasd/system_logs.zip) caused errno=2
Oct 26 20:08:55 Readynas apache2[18799]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:09:01 Readynas CRON[19630]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 26 20:09:01 Readynas CRON[19631]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Oct 26 20:09:01 Readynas CRON[19630]: pam_unix(cron:session): session closed for user root
Oct 26 20:09:05 Readynas connmand[2798]: ntp: time slew +0.061381 s
Oct 26 20:09:44 Readynas snapperd[19177]: Exiting
Oct 26 20:10:38 Readynas afpd[19688]: dsi_tcp_open: invalid header
Oct 26 20:15:38 Readynas apache2[18570]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:16:39 Readynas afpd[19873]: dsi_tcp_open: invalid header
Oct 26 20:17:01 Readynas CRON[19881]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 26 20:17:01 Readynas CRON[19882]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Oct 26 20:17:01 Readynas CRON[19881]: pam_unix(cron:session): session closed for user root
Oct 26 20:20:39 Readynas afpd[19976]: dsi_tcp_open: invalid header
Oct 26 20:22:42 Readynas apache2[4156]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:25:40 Readynas afpd[20111]: dsi_tcp_open: invalid header
Oct 26 20:26:09 Readynas connmand[2798]: ntp: time slew +0.061976 s
Oct 26 20:26:54 Readynas smbd[9115]: pam_unix(samba:session): session closed for user guest
Oct 26 20:26:55 Readynas dbus[2792]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Oct 26 20:26:55 Readynas snapperd[20143]: Requesting DBus name
Oct 26 20:26:55 Readynas dbus[2792]: [system] Successfully activated service 'org.opensuse.Snapper'
Oct 26 20:26:55 Readynas snapperd[20143]: Loading snapper configs
Oct 26 20:26:55 Readynas snapperd[20143]: Listening for method calls and signals
Oct 26 20:26:55 Readynas smbd[9115]: [2015/10/26 20:26:55.971549,  0] ../source3/modules/vfs_snapper.c:823(snapper_list_snaps_unpack)
Oct 26 20:26:55 Readynas smbd[9115]: list_snaps error response: error.no_permissions
Oct 26 20:26:55 Readynas smbd[9115]: [2015/10/26 20:26:55.972040,  0] ../source3/modules/vfs_default.c:1145(vfswrap_fsctl)
Oct 26 20:26:55 Readynas smbd[9115]: FSCTL_GET_SHADOW_COPY_DATA: connectpath /data/Alice, failed - NT_STATUS_ACCESS_DENIED.
Oct 26 20:28:56 Readynas snapperd[20143]: Exiting
Oct 26 20:29:45 Readynas apache2[4096]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:31:40 Readynas afpd[20269]: dsi_tcp_open: invalid header
Oct 26 20:35:41 Readynas afpd[20370]: dsi_tcp_open: invalid header
Oct 26 20:36:48 Readynas apache2[18443]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:39:01 Readynas CRON[20455]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 26 20:39:01 Readynas CRON[20456]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Oct 26 20:39:02 Readynas CRON[20455]: pam_unix(cron:session): session closed for user root
Oct 26 20:41:41 Readynas afpd[20536]: dsi_tcp_open: invalid header
Oct 26 20:43:13 Readynas connmand[2798]: ntp: time slew +0.061926 s
Oct 26 20:43:51 Readynas apache2[18521]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:44:07 Readynas noflushd[18930]: Spinning down disk 3 (/dev/sdb).
Oct 26 20:44:09 Readynas noflushd[18930]: Spindown of disk 3 (/dev/sdb) cancelled.
Oct 26 20:46:42 Readynas afpd[20665]: dsi_tcp_open: invalid header
Oct 26 20:49:10 Readynas noflushd[18930]: Spinning down disk 1 (/dev/sdd).
Oct 26 20:49:23 Readynas noflushd[18930]: Spinning up disk 1 (/dev/sdd) after 0:00:10.
Oct 26 20:49:33 Readynas noflushd[18930]: Spinning down disk 3 (/dev/sdb).
Oct 26 20:49:36 Readynas noflushd[18930]: Spinning down disk 2 (/dev/sdc).
Oct 26 20:49:43 Readynas noflushd[18930]: Spinning down disk 4 (/dev/sda).
Oct 26 20:49:55 Readynas noflushd[18930]: Spinning up disk 3 (/dev/sdb) after 0:00:19.
Oct 26 20:49:55 Readynas noflushd[18930]: Spinning up disk 2 (/dev/sdc) after 0:00:17.
Oct 26 20:50:00 Readynas noflushd[18930]: Spinning up disk 4 (/dev/sda) after 0:00:14.
Oct 26 20:50:42 Readynas afpd[20785]: dsi_tcp_open: invalid header
Oct 26 20:50:55 Readynas apache2[18478]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:54:31 Readynas noflushd[18930]: Spinning down disk 1 (/dev/sdd).
Oct 26 20:54:54 Readynas noflushd[18930]: Spinning up disk 1 (/dev/sdd) after 0:00:20.
Oct 26 20:55:04 Readynas noflushd[18930]: Spinning down disk 4 (/dev/sda).
Oct 26 20:55:07 Readynas noflushd[18930]: Spinning down disk 3 (/dev/sdb).
Oct 26 20:55:10 Readynas noflushd[18930]: Spinning down disk 2 (/dev/sdc).
Oct 26 20:55:17 Readynas noflushd[18930]: Spinning up disk 4 (/dev/sda) after 0:00:10.
Oct 26 20:55:18 Readynas noflushd[18930]: Spinning up disk 3 (/dev/sdb) after 0:00:08.
Oct 26 20:55:33 Readynas noflushd[18930]: Spinning up disk 2 (/dev/sdc) after 0:00:21.
Oct 26 20:56:42 Readynas afpd[20968]: dsi_tcp_open: invalid header
Oct 26 20:57:58 Readynas apache2[18799]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 20:58:13 Readynas dbus[2792]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Oct 26 20:58:13 Readynas snapperd[21010]: Requesting DBus name
Oct 26 20:58:13 Readynas dbus[2792]: [system] Successfully activated service 'org.opensuse.Snapper'
Oct 26 20:58:13 Readynas snapperd[21010]: Loading snapper configs
Oct 26 20:58:13 Readynas snapperd[21010]: Listening for method calls and signals
Oct 26 20:58:47 Readynas readynasd[3036]: Smart Snapshot successfully deleted snapshot 2015_10_24__21_00_09 from share or LUN Documents.
Oct 26 21:00:01 Readynas CRON[24203]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 26 21:00:01 Readynas CRON[24204]: (root) CMD (/etc/cron.hourly/log_rotate_script.sh)
Oct 26 21:00:01 Readynas CRON[24203]: pam_unix(cron:session): session closed for user root
Oct 26 21:00:17 Readynas connmand[2798]: ntp: time slew +0.063175 s
Oct 26 21:00:43 Readynas afpd[24224]: dsi_tcp_open: invalid header
Oct 26 21:00:44 Readynas snapperd[21010]: open failed path:/data/Documents/.snapshots/2496 (No such file or directory)
Oct 26 21:00:45 Readynas readynasd[3036]: Snapshot c_1445889644 was successfully created for share or LUN Documents.
Oct 26 21:02:45 Readynas snapperd[21010]: Exiting
Oct 26 21:05:01 Readynas apache2[4101]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 21:05:43 Readynas afpd[24367]: dsi_tcp_open: invalid header
Oct 26 21:05:54 Readynas noflushd[18930]: Spinning down disk 4 (/dev/sda).
Oct 26 21:05:57 Readynas noflushd[18930]: Spinning down disk 3 (/dev/sdb).
Oct 26 21:06:00 Readynas noflushd[18930]: Spinning down disk 1 (/dev/sdd).
Oct 26 21:06:05 Readynas noflushd[18930]: Spinning down disk 2 (/dev/sdc).
Oct 26 21:06:13 Readynas noflushd[18930]: Spinning up disk 4 (/dev/sda) after 0:00:16.
Oct 26 21:06:13 Readynas noflushd[18930]: Spinning up disk 3 (/dev/sdb) after 0:00:13.
Oct 26 21:06:28 Readynas noflushd[18930]: Spinning up disk 1 (/dev/sdd) after 0:00:23.
Oct 26 21:06:28 Readynas noflushd[18930]: Spinning up disk 2 (/dev/sdc) after 0:00:20.
Oct 26 21:09:01 Readynas CRON[24492]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 26 21:09:01 Readynas CRON[24493]: (root) CMD (  [ -x /usr/lib/php5/maxlifetime ] && [ -d /var/lib/php5 ] && find /var/lib/php5/ -depth -mindepth 1 -maxdepth 1 -type f -ignore_readdir_race -cmin +$(/usr/lib/php5/maxlifetime) ! -execdir fuser -s {} 2>/dev/null \; -delete)
Oct 26 21:09:01 Readynas CRON[24492]: pam_unix(cron:session): session closed for user root
Oct 26 21:11:19 Readynas noflushd[18930]: Spinning down disk 3 (/dev/sdb).
Oct 26 21:11:32 Readynas noflushd[18930]: Spinning down disk 1 (/dev/sdd).
Oct 26 21:11:35 Readynas noflushd[18930]: Spinning down disk 2 (/dev/sdc).
Oct 26 21:11:42 Readynas noflushd[18930]: Spinning up disk 3 (/dev/sdb) after 0:00:20.
Oct 26 21:11:42 Readynas noflushd[18930]: Spinning up disk 1 (/dev/sdd) after 0:00:07.
Oct 26 21:11:43 Readynas noflushd[18930]: Spinning up disk 2 (/dev/sdc) after 0:00:06.
Oct 26 21:11:43 Readynas afpd[24609]: dsi_tcp_open: invalid header
Oct 26 21:12:03 Readynas noflushd[18930]: Spinning down disk 4 (/dev/sda).
Oct 26 21:12:04 Readynas apache2[4094]: [error] [client 192.168.2.8] File does not exist: /var/www/currentsetting.htm
Oct 26 21:12:16 Readynas noflushd[18930]: Spinning up disk 4 (/dev/sda) after 0:00:10.
Oct 26 21:14:17 Readynas apache_access[4079]: Suppressed 34 duplicate messages
Oct 26 21:14:17 Readynas apache_access[4079]: 192.168.2.3 "POST /dbbroker HTTP/1.1" 401
Oct 26 21:14:19 Readynas apache_access[4079]: 192.168.2.3 "POST /dbbroker HTTP/1.1" 200
Oct 26 21:14:28 Readynas apache_access[4079]: 192.168.2.8 "GET / HTTP/1.1" 302
Oct 26 21:14:29 Readynas apache_access[4079]: 192.168.2.8 "POST /dbbroker HTTP/1.1" 200
Oct 26 21:14:33 Readynas apache_access[4079]: Suppressed 9 duplicate messages
Oct 26 21:14:33 Readynas apache_access[4079]: 192.168.2.8 "POST /fsbroker/ HTTP/1.1" 200
Oct 26 21:14:33 Readynas apache_access[4079]: 192.168.2.8 "POST /fwbroker HTTP/1.1" 200
Oct 26 21:14:33 Readynas apache_access[4079]: 192.168.2.8 "POST /dbbroker HTTP/1.1" 200
Oct 26 21:14:34 Readynas systemd[1]: [/lib/systemd/system/fvapp-surveillance.service:6] Invalid executable path in command line, ignoring: mkdir -p /var/nuuo/log
Oct 26 21:14:37 Readynas apache_access[4079]: Suppressed 1 duplicate messages
Oct 26 21:14:37 Readynas apache_access[4079]: 192.168.2.8 "POST /fwbroker HTTP/1.1" 200
Oct 26 21:14:38 Readynas apache_access[4079]: 192.168.2.8 "POST /dbbroker HTTP/1.1" 200
Oct 26 21:14:46 Readynas dbus[2792]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Oct 26 21:14:46 Readynas snapperd[25430]: Requesting DBus name
Oct 26 21:14:46 Readynas dbus[2792]: [system] Successfully activated service 'org.opensuse.Snapper'
Oct 26 21:14:46 Readynas snapperd[25430]: Loading snapper configs
Oct 26 21:14:46 Readynas snapperd[25430]: Listening for method calls and signals
Oct 26 21:14:47 Readynas readynasd[3036]: head: cannot open `/sys/kernel/config/target/iscsi/*/tpgt*/lun/lun*/lun*/info' for reading: No such file or directory
Oct 26 21:14:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.LeafNets.stable_secret"
Oct 26 21:14:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.all.stable_secret"
Oct 26 21:14:49 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.default.stable_secret"
Oct 26 21:14:50 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.eth0.stable_secret"
Oct 26 21:14:50 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.eth1.stable_secret"
Oct 26 21:14:50 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.lo.stable_secret"
Oct 26 21:14:50 Readynas readynasd[3036]: sysctl: reading key "net.ipv6.conf.tunl0.stable_secret"

NETGEAR Academy

Boost your skills with the Netgear Academy - Get trained, certified and stay ahead with the latest Netgear technology! 

Join Us!

ProSupport for Business

Comprehensive support plans for maximum network uptime and business peace of mind.

 

Learn More