NETGEAR is aware of a growing number of phone and online scams. To learn how to stay safe click here.
Forum Discussion
Solved
6.6.0 UPS alerts not sent
Had a power supply failure prior to the daily boot up at 08:00. Looking at the logs it appears that a controlled shutdown took place about 2 minutes after the initial startup. The UPS powers other ...
Btw ReadyNAS OS 6.6.1 is now available!
In the unlikely event you still have problems on 6.6.1 please start a new thread.
Thanks for that update evan2.
If you could provide that link when it is available for that beta fw.
ShoGinn wrote:
Thanks for that update evan2.
If you could provide that link when it is available for that beta fw.
It will be posted in the public beta forum here: https://community.netgear.com/t5/ReadyNAS-Beta-Release/bd-p/readynas-beta-releases?topic-zoom=Public%20ReadyNAS%20OS%206.x%20Beta&sort-by=&date-range=all#all-topics-top
Please update to ReadyNAS OS 6.6.1 Beta 1
I went guinea pig.
Update finshed 17:10, RN316 UPS status looks normal.
NV+ status went to normal a little later, ?~30s.
RAIDar took a few minutes to clear NV+ UPS error.
17:20 Changed UPS setting to AUTO (from 30%) - mention this re log below.
Extract of 'UPS' from system.log:
Nov 18 17:07:31 ME-NAS-316A usbhid-ups[3798]: Startup successful
Nov 18 17:07:31 ME-NAS-316A upsd[3800]: Startup successful
Nov 18 17:07:32 ME-NAS-316A upsmon[3812]: Startup successful
Nov 18 17:07:32 ME-NAS-316A upsd[3800]: User monuser@::1 logged into UPS [UPS]
Nov 18 17:07:32 ME-NAS-316A upsd[3800]: User monuser@::1 logged into UPS [UPS]
Nov 18 17:07:32 ME-NAS-316A upsd[3800]: User monuser@::1 logged into UPS [UPS]
Nov 18 17:09:03 ME-NAS-316A upsd[3800]: User monuser@192.168.1.10 logged into UPS [UPS]
Nov 18 17:09:03 ME-NAS-316A upsd[3800]: User monuser@192.168.1.10 logged into UPS [UPS]
Nov 18 17:09:03 ME-NAS-316A upsd[3800]: User monuser@192.168.1.10 logged into UPS [UPS]
Nov 18 17:20:46 ME-NAS-316A upsmon[3813]: Signal 15: exiting
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: User monuser@::1 logged out from UPS [UPS]
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: User monuser@::1 logged out from UPS [UPS]
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: User monuser@::1 logged out from UPS [UPS]
Nov 18 17:20:46 ME-NAS-316A usbhid-ups[3798]: Signal 15: exiting
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: Can't connect to UPS [UPS] (usbhid-ups-UPS): No such file or directory
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: Can't connect to UPS [UPS] (usbhid-ups-UPS): No such file or directory
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: Can't connect to UPS [UPS] (usbhid-ups-UPS): No such file or directory
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: Can't connect to UPS [UPS] (usbhid-ups-UPS): No such file or directory
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: Can't connect to UPS [UPS] (usbhid-ups-UPS): No such file or directory
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: mainloop: Interrupted system call
Nov 18 17:20:46 ME-NAS-316A upsd[3800]: Signal 15: exiting
Nov 18 17:20:46 ME-NAS-316A usbhid-ups[6556]: Startup successful
Nov 18 17:20:46 ME-NAS-316A upsd[6558]: Startup successful
Nov 18 17:20:46 ME-NAS-316A upsmon[6560]: Startup successful
Nov 18 17:20:46 ME-NAS-316A upsd[6558]: User monuser@::1 logged into UPS [UPS]
Nov 18 17:20:46 ME-NAS-316A upsd[6558]: User monuser@::1 logged into UPS [UPS]
Nov 18 17:20:46 ME-NAS-316A upsd[6558]: User monuser@::1 logged into UPS [UPS]
Nov 18 17:20:54 ME-NAS-316A upsd[6558]: User monuser@192.168.1.10 logged into UPS [UPS]
Nov 18 17:20:54 ME-NAS-316A upsd[6558]: User monuser@192.168.1.10 logged into UPS [UPS]
Nov 18 17:20:54 ME-NAS-316A upsd[6558]: User monuser@192.168.1.10 logged into UPS [UPS]Now 17:30, all looks normal.
I'll do a power fail test in a while.
Well, not all good...
RN316 Fri Nov 18 2016 19:07:52 System: Error communicating with UPS 'UPS' ().
Various frontview views look normal, but this is 7min later after AFK.
However I did get an email from the 316. Note NO followup email or log saying Comms OK, even tho above looks normal. (yes I refreshed frontview)
NV+ Health: UPS 1 Remote Error, Battery charge: 0%, 0 minutes Out of Spec
NV+ Log: Fri Nov 18 19:07:52 EST 2016 UPS Communication error.
System log following above log, same data stale messages:
Nov 18 17:21:42 ME-NAS-316A dbus[3338]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Nov 18 17:21:42 ME-NAS-316A dbus[3338]: [system] Successfully activated service 'org.opensuse.Snapper'
Nov 18 18:07:41 ME-NAS-316A dbus[3338]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Nov 18 18:07:41 ME-NAS-316A dbus[3338]: [system] Successfully activated service 'org.opensuse.Snapper'
Nov 18 18:16:59 ME-NAS-316A apache_access[4297]: Suppressed 597 duplicate messages
Nov 18 18:16:59 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 18:17:01 ME-NAS-316A CRON[12561]: pam_unix(cron:session): session opened for user root by (uid=0)
Nov 18 18:17:01 ME-NAS-316A CRON[12568]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly)
Nov 18 18:17:01 ME-NAS-316A CRON[12561]: pam_unix(cron:session): session closed for user root
Nov 18 19:07:42 ME-NAS-316A dbus[3338]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Nov 18 19:07:42 ME-NAS-316A dbus[3338]: [system] Successfully activated service 'org.opensuse.Snapper'
Nov 18 19:07:49 ME-NAS-316A upsd[6558]: Data for UPS [UPS] is stale - check driver
Nov 18 19:07:52 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:07:54 ME-NAS-316A msmtpq[17345]: mail for [ -C /etc/msmtprc michael...com.au --timeout=60 ] : send was successful
Nov 18 19:07:57 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:07:57 ME-NAS-316A upsd[6558]: User monuser@192.168.1.10 logged out from UPS [UPS]
Nov 18 19:08:02 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:08:07 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
...Nov 18 19:08:57 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:09:02 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:09:06 ME-NAS-316A apache_access[4297]: Suppressed 509 duplicate messages
Nov 18 19:09:06 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=volume&name=&value=oper&period=1_hour&width=1019&height=275&back=ffffff&randParam=0.5404600522758055 HTTP/1.1" 200
Nov 18 19:09:06 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=volume&name=&value=utilizn&period=1_hour&width=1019&height=261&back=ffffff&randParam=0.6902996265104178 HTTP/1.1" 200
Nov 18 19:09:06 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=network&name=&value=pkts&period=1_hour&width=1019&height=303&back=ffffff&proto=&randParam=0.013336750715785883 HTTP/1.1" 200
Nov 18 19:09:06 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=temperature&name=&value=deg&period=1_hour&width=1019&height=275&back=ffffff&randParam=0.391255569966801 HTTP/1.1" 200
Nov 18 19:09:07 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:09:07 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:09:12 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
...Nov 18 19:11:57 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:12:02 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:12:03 ME-NAS-316A apache_access[4297]: Suppressed 38 duplicate messages
Nov 18 19:12:03 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=volume&name=&value=oper&period=1_hour&width=1019&height=275&back=ffffff&randParam=0.24818174261631676 HTTP/1.1" 200
Nov 18 19:12:03 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=volume&name=&value=utilizn&period=1_hour&width=1019&height=261&back=ffffff&randParam=0.07640820144507465 HTTP/1.1" 200
Nov 18 19:12:03 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:12:03 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=network&name=&value=pkts&period=1_hour&width=1019&height=303&back=ffffff&proto=&randParam=0.5078713449378665 HTTP/1.1" 200
Nov 18 19:12:03 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=temperature&name=&value=deg&period=1_hour&width=1019&height=275&back=ffffff&randParam=0.47147658146671056 HTTP/1.1" 200
Nov 18 19:12:05 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:12:07 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:12:09 ME-NAS-316A apache_access[4297]: Suppressed 8 duplicate messages
Nov 18 19:12:09 ME-NAS-316A apache_access[4297]: 192.168.1.15 "GET /stimg?type=volume&name=&value=oper&period=1_hour&width=1019&height=275&back=ffffff&randParam=0.9777447772761799 HTTP/1.1" 200
Nov 18 19:12:09 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:12:12 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:12:14 ME-NAS-316A apache_access[4297]: Suppressed 4 duplicate messages
Nov 18 19:12:14 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /fsbroker/ HTTP/1.1" 200
Nov 18 19:12:14 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:12:14 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /fwbroker HTTP/1.1" 200
Nov 18 19:12:14 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:12:15 ME-NAS-316A apache_access[4297]: Suppressed 6 duplicate messages
Nov 18 19:12:15 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /fwbroker HTTP/1.1" 200
Nov 18 19:12:16 ME-NAS-316A apache_access[4297]: 192.168.1.15 "POST /dbbroker HTTP/1.1" 200
Nov 18 19:12:17 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:12:22 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
...Nov 18 19:13:12 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:13:17 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:13:21 ME-NAS-316A dbus[3338]: [system] Activating service name='org.opensuse.Snapper' (using servicehelper)
Nov 18 19:13:21 ME-NAS-316A dbus[3338]: [system] Successfully activated service 'org.opensuse.Snapper'
Nov 18 19:13:22 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Nov 18 19:13:27 ME-NAS-316A upsmon[6561]: Poll UPS [UPS@localhost] failed - Data stale
Just did a simulated power failure test. ie unplugged the UPS
Fail, same as before. RN316 did not notice, frontview views did not change from above reported status. No emails. Down to 15% 2minutes, no shutdown.
Again, changing the UPS settings gets the status working again, for a while.
Can you send in a fresh set of logs (see the Sending Logs link in my sig)?
NETGEAR Academy
Boost your skills with the Netgear Academy - Get trained, certified and stay ahead with the latest Netgear technology!
Join Us!
