Nut upsuser - Dec 2017 - SNMPv3 fails when more than one UPS is configured in ups.conf

Hi Charles,

Running upsdrvctl -D start on the host with all three configured to
SNMPv3 kicks out 56 different "unhandled ASN 0x81 from ..." lines on
all
three but importantly they all start up and keep running.

I'm starting to suspect the startup is taking so long that systemd is
timing out and killing it. time -p reports real time of 133.27. I'm not
a fan of systemd but it's a thing I have to deal with. I'm going to see
if I can find a way to tell it to give startup more time.

I'm attaching a -D startup in case anyone is curious (but I doubt anyone
will be. :)

nomad

On 12/3/17 14:34 , Charles Lepple wrote:
> On Nov 30, 2017, at 6:31 PM, Lee Damon <nomad at ee.washington.edu>
wrote:
>>
>> SNMPv3 works fine when I have any one of the three configured in
>> ups.conf while the other two are configured with SNMPv1. It doesn't
>> matter which _one_ UPS is configured for SNMPv3 in ups.conf, they all
>> work individually (see below example).
>>
>> However, if I configure any two or all three of them to use SNMPv3 then
>> on startup upsdrvctl complains about "unhandled ASN 0x81 received
from
>> .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.3" and fails to start.
>>
> Thanks for the detailed bug report. It's an interesting failure mode.
> 
> I created an issue on GitHub with a link back to the mail archive because
the developers who are most involved with SNMP are more likely to see it there
(and so it doesn't get lost; we're attempting to put together a new
release at the moment): https://github.com/networkupstools/nut/issues/508 (feel
free to reply to the list, or post there).
> 
> I suspect that the message you are seeing is from here in the snmp-ups
driver:
> 
>   
https://github.com/networkupstools/nut/blob/v2.7.2/drivers/snmp-ups.c#L706
> 
> (There are two other "unhandled ASN ..." messages, but due to the
use of upsdebugx(), they probably wouldn't show up the way that upsdrvctl
starts the drivers.)
> 
> To get more context on what is happening before this error, you can start
the driver directly with a few "-D" flags. The command line can be
found from a running driver, or you can pass "-D" to upsdrvctl itself
(I think; there have been some changes to that code over the years) and it will
show the whole command line.
> 
> Another option (which papers over the problem, but may help in the near
term) is to see if this is just a startup issue. You can specify one UPS with
"upsdrvctl start ups1", and then see if the other drivers still start
if you wait until the first driver has completed its SNMPv3 handshake. You can
automate this a bit with the "maxretry" and "retrydelay"
options in ups.conf: http://networkupstools.org/docs/man/ups.conf.html
> 
-------------- next part --------------
Script started on Mon 04 Dec 2017 09:32:50 AM PST
: || nomad at nomaddev ups [1001] ; time -p sudo /usr/sbin/upsdrvctl -D start
&& exit
Network UPS Tools - UPS driver controller 2.7.2
   0.000000	Starting UPS: ups1-1
Network UPS Tools - Generic SNMP UPS driver 0.72 (2.7.2)
Detected Smart-UPS 5000 on host ups1-1.[redacted] (mib: apcc 1.2)
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.2.1.4.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.2.3.5.0
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.2.3.6.0
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.2.1.4.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.2
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.3
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.1
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.2
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.3
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.1
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.2
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.3
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.1
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.2
[ups1-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.3
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.2.1.1.0
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.2.1.2.0
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.10.2.3.2.1.4.1
[ups1-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.10.2.3.2.1.6.1
  42.773758	Starting UPS: ups2-1
Network UPS Tools - Generic SNMP UPS driver 0.72 (2.7.2)
Detected Smart-UPS 5000 on host ups2-1.[redacted] (mib: apcc 1.2)
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.2.1.4.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.2.3.5.0
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.2.3.6.0
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.2.1.4.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.1
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.2
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.3
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.1
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.2
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.3
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.1
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.2
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.3
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.1
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.2
[ups2-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.3
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.2.1.1.0
[ups2-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.2.1.2.0
  87.478686	Starting UPS: ups3-1
Network UPS Tools - Generic SNMP UPS driver 0.72 (2.7.2)
Detected Smart-UPS 5000 on host ups3-1.[redacted] (mib: apcc 1.2)
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.3.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.4.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.5.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.6.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.7.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.3.1.8.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.2.2.1.4.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.2.3.5.0
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.2.3.6.0
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.2.1.4.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.3.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.4.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.5.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.6.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.7.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.8.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.2
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.1.9.3.3.1.9.1.1.3
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.1
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.2
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.10.1.1.3
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.1
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.2
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.11.1.1.3
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.1
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.2
[ups3-1] unhandled ASN 0x81 received from
.1.3.6.1.4.1.318.1.1.1.9.3.3.1.12.1.1.3
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.2.1.1.0
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.2.1.2.0
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.10.2.3.2.1.4.1
[ups3-1] unhandled ASN 0x81 received from .1.3.6.1.4.1.318.1.1.10.2.3.2.1.6.1
real 133.51
user 0.13
sys 0.03
exit

Script done on Mon 04 Dec 2017 09:35:13 AM PST

I've told systemd to wait up to 10 minutes for startup but it's still
failing. Sometimes it fails with "timeout exceeded" and sometimes with
the ASN error previously reported.

I went back to running upsdrvctl -D to see if I could see anything. On
the third run I saw messages about "startup timer elapsed" for ups2-1
and then it tried again to launch that one (killing the existing one).
It did this multiple times. When trying to systemd I see a bunch of
zombie processes that used to be snmp-ups for one of ups2-1 or ups3-1
(depending on the run).

I'm trying again with
 /etc/systemd/system/nut-driver.service.d/nut-driver.conf set to:
  [Service]
  TimeoutSec=600
and
 /etc/ups/ups.conf having
  maxretry = 10
  retrydelay = 15

This time ps shows three apparently happy snmp-ups processes but
upsdrvctl start is still showing up. ... and then everything vanishes
but systemd doesn't complain. journalctl -xe shows a good startup then
claims nut-driver.service isn't needed anymore and shuts it down:

Dec 04 10:17:52 [redacted] snmp-ups[14578]: Startup successful
Dec 04 10:17:52 [redacted] upsdrvctl[14556]: Network UPS Tools - UPS
driver controller 2.7.2
Dec 04 10:17:52 [redacted] systemd[1]: Started Network UPS Tools - power
device driver controller.
-- Subject: Unit nut-driver.service has finished start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit nut-driver.service has finished starting up.
--
-- The start-up result is done.
Dec 04 10:17:52 [redacted] systemd[1]: Unit nut-driver.service is not
needed anymore. Stopping.

I have a feeling that if I do get this working by setting huge timers
I'm just cargo-culting a "fix".

nomad

On 12/4/17 09:38 , Lee Damon wrote:
> Hi Charles,
> 
> Running upsdrvctl -D start on the host with all three configured to
> SNMPv3 kicks out 56 different "unhandled ASN 0x81 from ..." lines
on all
> three but importantly they all start up and keep running.
> 
> I'm starting to suspect the startup is taking so long that systemd is
> timing out and killing it. time -p reports real time of 133.27. I'm not
> a fan of systemd but it's a thing I have to deal with. I'm going to
see
> if I can find a way to tell it to give startup more time.
> 
> I'm attaching a -D startup in case anyone is curious (but I doubt
anyone
> will be. :)
> 
> nomad

I have it "working" with the following settings changes from the
default
in the RPM. By "working" I mean it starts after reboot and after
issuing
'sudo systemctl restart nut-driver' but, as expected, it takes quite a
while to finish startup.

/etc/systemd/system/nut-driver.service.d/nut-driver.conf
[Unit]
StopWhenUnneeded=no

[Service]
TimeoutSec=600



/etc/ups/ups.conf
...
maxretry = 10
retrydelay = 15
...


As I said, I suspect the bulk of these are cargo cult "it worked, not
messing with it" settings.

nomad