[Nut-upsdev] newhidups (2.03-pre2) not working with APC Back-UPS

[Charlie Brady]

On Tue, 31 Jan 2006, Charlie Brady wrote:

> I'm having lots of comms problems still.  I think I saw something in the 
> archives about increasing timeouts to deal with slow hardware - is that 
> what's likely going on here?
> 
> ...
> Jan 31 10:12:48 test7 upsmon[11845]: Poll UPS [UPS at localhost] failed - 
> Data stale
> Jan 31 10:12:48 test7 upsmon[11845]: UPS UPS at localhost is unavailable
> Jan 31 10:12:48 test7 wall[20052]: wall: user nut broadcasted 1 lines (34 
> chars)
> Jan 31 10:12:53 test7 upsmon[11845]: Poll UPS [UPS at localhost] failed - 
> Data stale

This is what was happening in upsd:

...
Process 11840 attached - interrupt to quit
select(7, [3 5 6], NULL, NULL, {1, 669000}) = 0 (Timeout)
time([1138736893])                      = 1138736893
select(7, [3 5 6], NULL, NULL, {2, 0})  = 1 (in [6], left {0, 998000})
read(6, "GET VAR UPS ups.status\n", 512) = 23
write(6, "ERR DATA-STALE\n", 15)        = 15
time([1138736894])                      = 1138736894
select(7, [3 5 6], NULL, NULL, {2, 0})  = 0 (Timeout)
time([1138736896])                      = 1138736896
select(7, [3 5 6], NULL, NULL, {2, 0})  = 0 (Timeout)
time([1138736898])                      = 1138736898
select(7, [3 5 6], NULL, NULL, {2, 0})  = 1 (in [6], left {0, 999000})
read(6, "GET VAR UPS ups.status\n", 512) = 23
write(6, "ERR DATA-STALE\n", 15)        = 15
time([1138736899])                      = 1138736899
select(7, [3 5 6], NULL, NULL, {2, 0})  = 0 (Timeout)
time([1138736901])                      = 1138736901
select(7, [3 5 6], NULL, NULL, {2, 0})  = 0 (Timeout)
time([1138736903])                      = 1138736903
...

I restarted the nut service and it's back working happily:

...
select(7, [3 5 6], NULL, NULL, {1, 854000}) = 1 (in [6], left {0, 999000})
read(6, "GET VAR UPS ups.status\n", 512) = 23
write(6, "VAR UPS ups.status \"OL\"\n", 24) = 24
time([1138736939])                      = 1138736939
select(7, [3 5 6], NULL, NULL, {2, 0})  = 0 (Timeout)
time([1138736941])                      = 1138736941
write(5, "PING\n", 5)                   = 5
select(7, [3 5 6], NULL, NULL, {2, 0})  = 1 (in [5], left {2, 0})
read(5, "P", 1)                         = 1
read(5, "O", 1)                         = 1
read(5, "N", 1)                         = 1
read(5, "G", 1)                         = 1
read(5, "\n", 1)                        = 1
time([1138736941])                      = 1138736941
time([1138736941])                      = 1138736941
select(7, [3 5 6], NULL, NULL, {2, 0})  = 0 (Timeout)
time([1138736943])                      = 1138736943
select(7, [3 5 6], NULL, NULL, {2, 0})  = 1 (in [6], left {1, 2000})
...

It looks as though upsd had stopping pinging the driver. Why would that 
be?

What should I do to investigate if/when this happens again?

--
Charlie