Problems with Eaton E series UPS

[GoneTomorrow]

So I got a UPS for my in-progress FreeNAS server, since, although the mains is very stable here with perhaps one outage per year, they are cheap enough and provide surge protection as well.

So I went out and got a low-end Eaton E NV 600VA UPS, which will provide more than enough power for my server to shutdown safely. I chose this UPS because of it's 'supposed' excellent support with NUT (5 stars apparently).

However the issue I have is that after a random amount of time, the driver just stops communicating with the UPS, and requires a restart of NUT to fix. After searching around, this seems to maybe be a known problem as I've seen a couple of instances of this happening to other people.

My current workaround is a cron job that restarts NUT every 6 hours, but still sometimes the driver will screw up shortly after, leaving 6 hours where the server has no contact with the UPS. I have also reduced the polling interval to 20 seconds, which made it slightly less frequent. I have also replaced the USB cable.

Log messages showing the problem, the first line shows the UPS coming online after a NUT restart at midnight, at which point it works fine until 2:40 later, when it just dies for no reason. Theres another NUT restart at 6am:

Code:

Dec 29 00:00:09 freenas upsmon[74906]: Communications with UPS ups established
Dec 29 02:41:52 freenas blazer_usb[98556]: Permissions problem: Input/output error
Dec 29 02:41:52 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 02:41:56 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:41:56 freenas upsmon[74906]: Communications with UPS ups lost
Dec 29 02:42:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:42:31 freenas last message repeated 6 times
Dec 29 02:44:31 freenas last message repeated 24 times
Dec 29 02:46:51 freenas last message repeated 28 times
Dec 29 02:46:51 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 02:46:53 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 02:46:56 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:47:31 freenas last message repeated 7 times
Dec 29 02:49:31 freenas last message repeated 24 times
Dec 29 02:51:51 freenas last message repeated 28 times
Dec 29 02:51:53 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 02:51:56 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:51:56 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 02:52:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:52:31 freenas last message repeated 6 times
Dec 29 02:54:31 freenas last message repeated 24 times
Dec 29 02:56:52 freenas last message repeated 28 times
Dec 29 02:56:54 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 02:56:57 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:56:57 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 02:57:02 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 02:57:32 freenas last message repeated 6 times
Dec 29 02:59:27 freenas last message repeated 23 times
Dec 29 03:01:52 freenas last message repeated 29 times
Dec 29 03:01:54 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:01:57 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:02:02 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:02:02 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:02:07 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:02:42 freenas last message repeated 7 times
Dec 29 03:04:47 freenas last message repeated 25 times
Dec 29 03:06:52 freenas last message repeated 25 times
Dec 29 03:06:54 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:06:57 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:07:07 freenas last message repeated 2 times
Dec 29 03:07:07 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:07:12 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:07:47 freenas last message repeated 7 times
Dec 29 03:09:52 freenas last message repeated 25 times
Dec 29 03:11:52 freenas last message repeated 24 times
Dec 29 03:11:54 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:11:57 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:12:12 freenas last message repeated 3 times
Dec 29 03:12:12 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:12:17 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:12:52 freenas last message repeated 7 times
Dec 29 03:14:57 freenas last message repeated 25 times
Dec 29 03:16:53 freenas last message repeated 23 times
Dec 29 03:16:55 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:16:58 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:17:13 freenas last message repeated 3 times
Dec 29 03:17:13 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:17:18 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:17:53 freenas last message repeated 7 times
Dec 29 03:19:58 freenas last message repeated 25 times
Dec 29 03:21:53 freenas last message repeated 23 times
Dec 29 03:21:55 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:21:58 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:22:18 freenas last message repeated 4 times
Dec 29 03:22:18 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:22:23 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:22:58 freenas last message repeated 7 times
Dec 29 03:24:58 freenas last message repeated 24 times
Dec 29 03:26:53 freenas last message repeated 23 times
Dec 29 03:26:55 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:26:58 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:27:23 freenas last message repeated 5 times
Dec 29 03:27:23 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:27:28 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:27:58 freenas last message repeated 6 times
Dec 29 03:29:58 freenas last message repeated 24 times
Dec 29 03:31:53 freenas last message repeated 23 times
Dec 29 03:31:55 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:31:58 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:32:24 freenas last message repeated 5 times
Dec 29 03:32:24 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:32:29 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:32:59 freenas last message repeated 6 times
Dec 29 03:34:59 freenas last message repeated 24 times
Dec 29 03:36:54 freenas last message repeated 23 times
Dec 29 03:36:56 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:36:59 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:37:29 freenas last message repeated 6 times
Dec 29 03:37:29 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:37:34 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:38:09 freenas last message repeated 7 times
Dec 29 03:40:14 freenas last message repeated 25 times
Dec 29 03:41:54 freenas last message repeated 20 times
Dec 29 03:41:56 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:41:59 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:42:29 freenas last message repeated 6 times
Dec 29 03:42:34 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:42:34 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:42:39 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:43:14 freenas last message repeated 7 times
Dec 29 03:45:19 freenas last message repeated 25 times
Dec 29 03:46:54 freenas last message repeated 19 times
Dec 29 03:46:56 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:46:59 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:47:29 freenas last message repeated 6 times
Dec 29 03:47:39 freenas last message repeated 2 times
Dec 29 03:47:39 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:47:44 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:48:19 freenas last message repeated 7 times
Dec 29 03:50:24 freenas last message repeated 25 times
Dec 29 03:51:54 freenas last message repeated 18 times
Dec 29 03:51:56 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:51:59 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:52:29 freenas last message repeated 6 times
Dec 29 03:52:44 freenas last message repeated 3 times
Dec 29 03:52:44 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:52:49 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:53:24 freenas last message repeated 7 times
Dec 29 03:55:25 freenas last message repeated 24 times
Dec 29 03:56:55 freenas last message repeated 18 times
Dec 29 03:56:57 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 03:57:00 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:57:30 freenas last message repeated 6 times
Dec 29 03:57:45 freenas last message repeated 3 times
Dec 29 03:57:45 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 03:57:50 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 03:58:25 freenas last message repeated 7 times
Dec 29 04:00:30 freenas last message repeated 25 times
Dec 29 04:01:55 freenas last message repeated 17 times
Dec 29 04:01:57 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:02:00 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:02:30 freenas last message repeated 6 times
Dec 29 04:02:50 freenas last message repeated 4 times
Dec 29 04:02:50 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:02:55 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:03:30 freenas last message repeated 7 times
Dec 29 04:05:30 freenas last message repeated 24 times
Dec 29 04:06:55 freenas last message repeated 17 times
Dec 29 04:06:57 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:07:00 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:07:30 freenas last message repeated 6 times
Dec 29 04:07:55 freenas last message repeated 5 times
Dec 29 04:07:55 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:08:00 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:08:31 freenas last message repeated 6 times
Dec 29 04:10:31 freenas last message repeated 24 times
Dec 29 04:11:56 freenas last message repeated 17 times
Dec 29 04:11:58 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:12:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:12:31 freenas last message repeated 6 times
Dec 29 04:12:56 freenas last message repeated 5 times
Dec 29 04:12:56 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:13:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:13:31 freenas last message repeated 6 times
Dec 29 04:15:31 freenas last message repeated 24 times
Dec 29 04:16:56 freenas last message repeated 17 times
Dec 29 04:16:58 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:17:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:17:31 freenas last message repeated 6 times
Dec 29 04:18:01 freenas last message repeated 6 times
Dec 29 04:18:01 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:18:06 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:18:41 freenas last message repeated 7 times
Dec 29 04:20:42 freenas last message repeated 24 times
Dec 29 04:21:57 freenas last message repeated 15 times
Dec 29 04:21:59 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:22:02 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:22:32 freenas last message repeated 6 times
Dec 29 04:23:02 freenas last message repeated 6 times
Dec 29 04:23:02 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:23:07 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:23:42 freenas last message repeated 7 times
Dec 29 04:25:47 freenas last message repeated 25 times
Dec 29 04:26:57 freenas last message repeated 14 times
Dec 29 04:26:59 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:27:02 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:27:27 freenas last message repeated 5 times
Dec 29 04:28:07 freenas last message repeated 8 times
Dec 29 04:28:07 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:28:12 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:28:47 freenas last message repeated 7 times
Dec 29 04:30:52 freenas last message repeated 25 times
Dec 29 04:31:57 freenas last message repeated 13 times
Dec 29 04:31:59 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:32:02 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:32:27 freenas last message repeated 5 times
Dec 29 04:33:12 freenas last message repeated 9 times
Dec 29 04:33:12 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:33:17 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:33:52 freenas last message repeated 7 times
Dec 29 04:35:57 freenas last message repeated 25 times
Dec 29 04:36:58 freenas last message repeated 12 times
Dec 29 04:37:00 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:37:03 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:37:38 freenas last message repeated 7 times
Dec 29 04:38:13 freenas last message repeated 7 times
Dec 29 04:38:13 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:38:18 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:38:53 freenas last message repeated 7 times
Dec 29 04:40:58 freenas last message repeated 25 times
Dec 29 04:41:58 freenas last message repeated 12 times
Dec 29 04:42:00 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:42:03 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:42:38 freenas last message repeated 7 times
Dec 29 04:43:18 freenas last message repeated 8 times
Dec 29 04:43:18 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:43:23 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:43:58 freenas last message repeated 7 times
Dec 29 04:45:58 freenas last message repeated 24 times
Dec 29 04:46:58 freenas last message repeated 12 times
Dec 29 04:47:00 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:47:03 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:47:38 freenas last message repeated 7 times
Dec 29 04:48:23 freenas last message repeated 9 times
Dec 29 04:48:23 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:48:28 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:48:58 freenas last message repeated 6 times
Dec 29 04:50:58 freenas last message repeated 24 times
Dec 29 04:51:58 freenas last message repeated 12 times
Dec 29 04:52:00 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:52:03 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:52:38 freenas last message repeated 7 times
Dec 29 04:53:28 freenas last message repeated 10 times
Dec 29 04:53:28 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:53:33 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:54:08 freenas last message repeated 7 times
Dec 29 04:56:09 freenas last message repeated 24 times
Dec 29 04:56:59 freenas last message repeated 10 times
Dec 29 04:57:01 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 04:57:04 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:57:39 freenas last message repeated 7 times
Dec 29 04:58:29 freenas last message repeated 10 times
Dec 29 04:58:29 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 04:58:34 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 04:59:09 freenas last message repeated 7 times
Dec 29 05:01:14 freenas last message repeated 25 times
Dec 29 05:01:59 freenas last message repeated 9 times
Dec 29 05:02:01 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:02:04 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:02:39 freenas last message repeated 7 times
Dec 29 05:03:34 freenas last message repeated 11 times
Dec 29 05:03:34 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:03:39 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:04:14 freenas last message repeated 7 times
Dec 29 05:06:19 freenas last message repeated 25 times
Dec 29 05:06:59 freenas last message repeated 8 times
Dec 29 05:07:01 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:07:04 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:07:39 freenas last message repeated 7 times
Dec 29 05:08:39 freenas last message repeated 12 times
Dec 29 05:08:39 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:08:44 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:09:19 freenas last message repeated 7 times
Dec 29 05:11:20 freenas last message repeated 24 times
Dec 29 05:12:00 freenas last message repeated 8 times
Dec 29 05:12:02 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:12:05 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:12:40 freenas last message repeated 7 times
Dec 29 05:13:40 freenas last message repeated 12 times
Dec 29 05:13:40 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:13:45 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:14:20 freenas last message repeated 7 times
Dec 29 05:16:25 freenas last message repeated 25 times
Dec 29 05:17:00 freenas last message repeated 7 times
Dec 29 05:17:02 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:17:05 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:17:40 freenas last message repeated 7 times
Dec 29 05:18:45 freenas last message repeated 13 times
Dec 29 05:18:45 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:18:50 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:19:25 freenas last message repeated 7 times
Dec 29 05:21:30 freenas last message repeated 25 times
Dec 29 05:22:00 freenas last message repeated 6 times
Dec 29 05:22:02 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:22:05 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:22:40 freenas last message repeated 7 times
Dec 29 05:23:50 freenas last message repeated 14 times
Dec 29 05:23:50 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:23:55 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:24:30 freenas last message repeated 7 times
Dec 29 05:26:30 freenas last message repeated 24 times
Dec 29 05:27:00 freenas last message repeated 6 times
Dec 29 05:27:02 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:27:05 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:27:40 freenas last message repeated 7 times
Dec 29 05:28:55 freenas last message repeated 15 times
Dec 29 05:28:55 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:29:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:29:31 freenas last message repeated 6 times
Dec 29 05:31:31 freenas last message repeated 24 times
Dec 29 05:32:01 freenas last message repeated 6 times
Dec 29 05:32:03 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:32:06 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:32:41 freenas last message repeated 7 times
Dec 29 05:33:56 freenas last message repeated 15 times
Dec 29 05:33:56 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:34:01 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:34:31 freenas last message repeated 6 times
Dec 29 05:36:31 freenas last message repeated 24 times
Dec 29 05:37:01 freenas last message repeated 6 times
Dec 29 05:37:03 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:37:06 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:37:41 freenas last message repeated 7 times
Dec 29 05:39:01 freenas last message repeated 16 times
Dec 29 05:39:01 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:39:06 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:39:41 freenas last message repeated 7 times
Dec 29 05:41:46 freenas last message repeated 25 times
Dec 29 05:42:01 freenas last message repeated 3 times
Dec 29 05:42:03 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:42:06 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:42:41 freenas last message repeated 7 times
Dec 29 05:44:06 freenas last message repeated 17 times
Dec 29 05:44:06 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:44:11 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:44:46 freenas last message repeated 7 times
Dec 29 05:46:51 freenas last message repeated 25 times
Dec 29 05:47:01 freenas last message repeated 2 times
Dec 29 05:47:03 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:47:06 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:47:41 freenas last message repeated 7 times
Dec 29 05:49:12 freenas last message repeated 18 times
Dec 29 05:49:12 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:49:17 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:49:52 freenas last message repeated 7 times
Dec 29 05:51:57 freenas last message repeated 25 times
Dec 29 05:52:02 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:52:04 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:52:07 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:52:42 freenas last message repeated 7 times
Dec 29 05:54:17 freenas last message repeated 19 times
Dec 29 05:54:17 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:54:22 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:54:57 freenas last message repeated 7 times
Dec 29 05:57:02 freenas last message repeated 25 times
Dec 29 05:57:04 freenas upsd[98558]: Can't connect to UPS [ups] (blazer_usb-ups): No such file or directory
Dec 29 05:57:07 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:57:42 freenas last message repeated 7 times
Dec 29 05:59:22 freenas last message repeated 20 times
Dec 29 05:59:22 freenas upsmon[74906]: UPS ups is unavailable
Dec 29 05:59:27 freenas upsmon[74906]: Poll UPS [ups] failed - Driver not connected
Dec 29 05:59:57 freenas last message repeated 6 times
Dec 29 06:00:01 freenas upsd[98558]: mainloop: Interrupted system call
Dec 29 06:00:02 freenas root: Unknown USB device: vendor 0x0665 product 0x5161 bus uhub2
Dec 29 06:00:02 freenas upsmon[74906]: Poll UPS [ups] failed - Write error: Broken pipe
Dec 29 06:00:07 freenas upsmon[74906]: Communications with UPS ups established

Any help please, I can't really start using the server until I have a solution.

Setup:

HP MicroServer G7 2.2GHz dual core
FreeNAS-9.2.0-RELEASE-x64
8GB ECC RAM
3x Seagate NAS drives in RAIDZ
Eaton E series NV 600VA UPS

 

[dlavigne]

Does your situation seem related to the cabling comment at https://bugs.freenas.org/issues/2513?

 

[GoneTomorrow]

Not really sure what the actual outcome of that bug was, but according to the NUT database, the correct driver for my UPS is blazer_usb, with 5 star support. This is the driver I am using.

 

[dlavigne]

It's worthwhile creating a separate bug report with the errors included and posting the issue number here.

Is there a pattern to when the connection is lost? e.g. every X hours or a certain time of day?

 

[SteveBuscemi]

Long standing bug. I have the same problem — a random Input/out error in blazer_usb bringing down the ups — it’s existed for me since FreeNAS 8.x.

Changing the poll interval does make some difference but there isn't really a pattern as to when it fails -- some times days, others hours. I've just learned to live with the cronjob.

For what it's worth, NAS4Free users also reported the same problem years ago. Neither nut updates nor the BSD subsystem moving from 8.x to 9.x achieved anything/
http://forums.nas4free.org/viewtopic.php?f=46&t=5321&p=28904
http://forums.nas4free.org/viewtopic.php?f=46&t=946

 

[GoneTomorrow]

Yeah I had seen that looking at a few pages after Googling the console messages, some threads dating way back.

Funny to think that Eaton is so involved with NUT and their UPS's are supposedly well supported, yet they still have serious issues such as these.

 

[GoneTomorrow]

What are peoples settings for poll interval and NUT-restart cron jobs?

I have a 30 second poll interval and a 6 hourly NUT restart and I just had two consecutive events where the UPS disconnected 30 seconds after a NUT restart, so basically 12 hours where the UPS wasn't monitored. Not acceptable.

 

[sgny]

I've found that it's sufficient to simply reload the blazer_usb driver when it stops communicating properly. So, I've setup a cron job polls the ups with upsc every minute and runs the command

/opt/nut/bin/blazer_usb -a <name of ups in ups.conf>

if upsc does not return the status of the ups. Running blazer_usb will first unload the previous instance of the driver, so I find this to be a more elegant solution than restarting NUT. There is no need to restart upsd or upsmon, since they seem to be running without issues.

 

[GoneTomorrow]

I've found that it's sufficient to simply reload the blazer_usb driver when it stops communicating properly. So, I've setup a cron job polls the ups with upsc every minute and runs the command

if upsc does not return the status of the ups. Running blazer_usb will first unload the previous instance of the driver, so I find this to be a more elegant solution than restarting NUT. There is no need to restart upsd or upsmon, since they seem to be running without issues.

Can you post the full config for that?

 

[sgny]

Sure. I have NUT installed at /opt/nut and my UPS is called eaton in /opt/nut/etc/ups.conf. I've created the below cron job:

* * * * * /opt/nut/etc/ups_check.sh

which runs a very simple script that checks for a string that exists in the output of upsc when it properly returns UPS status and runs blazer_usb otherwise.

#!/bin/bash
if [[ $(/opt/nut/bin/upsc eaton | grep 'ups.status') == *OL ]]; then
exit 0
else
/opt/nut/bin/blazer_usb -a eaton
fi

I suppose it might be better to search for error messages in upsc output, but I didn't want to bother making sure to cover every possible error state.

As you have experienced, sometimes the driver stops responding within a few minutes but at other times it continues to work for several hours.

Hope this helps.

 

[GoneTomorrow]

Sure. I have NUT installed at /opt/nut and my UPS is called eaton in /opt/nut/etc/ups.conf. I've created the below cron job:

which runs a very simple script that checks for a string that exists in the output of upsc when it properly returns UPS status and runs blazer_usb otherwise.


I suppose it might be better to search for error messages in upsc output, but I didn't want to bother making sure to cover every possible error state.

As you have experienced, sometimes the driver stops responding within a few minutes but at other times it continues to work for several hours.

Hope this helps.

Thanks. I'd like to implement this, however I am not sure what the default directories are in FreeNAS, and cannot find anything on the Internet. I have found the ups.conf, but not where upsc is living. Would you know there this is by default?

 

[sgny]

I cannot exactly help you with that as I'm not running FreeNAS on my server.

Some general info could be useful to you, however. There are two proper ways to distribute files across the filesystem. You could have executables (such as upsc, upsmon, etc.) under /bin (or /usr/bin or /usr/local/bin) or /sbin (or /usr/sbin or /usr/local/sbin) if they require root privileges, libraries under /lib (or /usr/lib or /usr/local/lib) and config files under /etc (or rarely /usr/local/etc). Otherwise, you could have a directory to keep all files under /opt in the case of my system or perhaps /usr/local. Then, you'd find etc, bin, lib, sbin within that directory. So, if you were able to locate ups.conf in <some path>/etc , you would either find the executables in <same path>/bin or you should have upsc under /bin or /usr/bin.

Failing all that, just search for upsc. cd to / and run

find . | grep upsc

Good luck and thank you for having started this topic. Until I read dlavigne and SteveBuscemi's posts I couldn't figure out what was wrong because I was compiling from source and this UPS is supposed to be so well supported with Eaton support behind NUT. I joined the forum because I felt I had an OK solution.

 

[GoneTomorrow]

Cheers, got it all working. The script ended up looking like:

Code:

#!/bin/bash
if [[ $(/usr/local/bin/upsc ups | grep 'ups.status') == *OL ]]; then
exit 0
else
/usr/local/libexec/nut/blazer_usb -a ups
fi

And since FreeNAS is embedded, the script needed to go somewhere like /mnt/storage/.system/scripts/ to be persistent after reboot.

Thanks for the help, the UPS now spends a useful amount of time talking to the server.

 

[dantest69]

Thanks a million GoneTomorrow for your script and helpful instructions. It's very kind of you to add that information to the FreeNAS repository of knowledge as I was having the exact same issue.

I've just added your script as a cron job and will keep an eye on it. I also added pollinterval=10 to the ups.conf box in the FreeNAS UPS settings dialog box based on the advice of someone in a different forum.

Will reply soon with an update after I monitor for a few days.

Thanks again!

 

[Paranoiak]

I have setup a SOCOMEC Netys Pe 650 VA plugged into the USB port and I had the same issues !

http://www.socomec.com/range-ups-single-phase_en.html?product=/ups-netys-pe_en.html

The bash resolved them, so a BIG thank you !

 

[Paranoiak]

I have just this message when I unplug the UPS for 30 seconds (shutdown init after that) :

Duplicate driver instance detected! Terminating other driver!
Supported UPS detected with megatec protocol
Vendor information unavailable
No values provided for battery high/low voltages in ups.conf

Using 'guestimation' (low: 10.400000, high: 13.000000)!
Battery runtime will not be calculated (runtimecal not set)

It's not really a problem, when I reboot it is working again.

 

[josch]

Cheers, got it all working. The script ended up looking like:

Code:

#!/bin/bash
if [[ $(/usr/local/bin/upsc ups | grep 'ups.status') == *OL ]]; then
exit 0
else
/usr/local/libexec/nut/blazer_usb -a ups
fi

And since FreeNAS is embedded, the script needed to go somewhere like /mnt/storage/.system/scripts/ to be persistent after reboot.

Thanks for the help, the UPS now spends a useful amount of time talking to the server.

There is a problem with this script!
ups.status is only OL when the UPS is Online (line power available and good)
When you need your UPS the most, this script will keep restarting the driver!
Better check for something that never changes!
My script is:

Code:

#!/bin/bash
if [[ $(/usr/local/bin/upsc ups | grep 'device.type') == *ups ]]; then
exit 0
else
/usr/local/libexec/nut/blazer_usb -a ups
fi

The type will always be ups, even when the line power fails!

 

[qbaqba]

Is this script (+ cron job) still the best walkaround for this issue?

 

[SLIMaxPower]

both of these scripts give me Syntax error: "fi" unexpected (expecting "then")

i called the script ups.sh and moved it to mnt/pool/blah.

ran the script manually in shell, and cron

 

[vbaForFun]

both of these scripts give me Syntax error: "fi" unexpected (expecting "then")

i called the script ups.sh and moved it to mnt/pool/blah.

ran the script manually in shell, and cron

Not sure if you're still having that issue, but I also had it. I found that this was due to having created the script as a file on a windows machine and moved it over.
If you create the file directly through the CLI (I just used the nano command) then it runs fine.

Hope that helps, or helps others with this same issue.