[Nut-upsuser] Back UPS ES and data stale with disconnected device

[Denis Serov]

Hello NUT Team!

I continue playing with NUT for Windows and USBHID-UPS driver I've got from Frederic to fix "Data Stale" problem.

It seems there is something wrong when Network UPS Tools service is starting with disconnected USB cable. UPSMON is writing "Data Stale" to output  and continue do it infinitely, even the cable is connected back. Here is a short log:

Network UPS Tools upsmon 2.6.5-3691:3709M
   0.000000     UPS: upsa at 192.168.10.200 (master) (power value 1)
   0.015600     Using power down flag file C:\killpower
   0.015600     debug level is '5'
   0.015600     Trying to connect to UPS [upsa at 192.168.10.200]
   0.031200     Login on UPS [upsa at 192.168.10.200] failed - got [ERR ACCESS-DENIED]
   0.031200     Current power value: 1
   0.046800     Minimum power value: 1
   5.054409     pollups: upsa at 192.168.10.200
   5.054409     get_var: upsa at 192.168.10.200 / status
   5.054409     Poll UPS [upsa at 192.168.10.200] failed - Data stale
   5.070009     do_notify: ntype 0x0005 (COMMBAD)
   5.070009     Communications with UPS upsa at 192.168.10.200 lost
   5.085609     Current power value: 1
   5.085609     Minimum power value: 1
  10.097895     pollups: upsa at 192.168.10.200
  10.097895     get_var: upsa at 192.168.10.200 / status
  10.097895     Poll UPS [upsa at 192.168.10.200] failed - Data stale
  10.113495     do_notify: ntype 0x0008 (NOCOMM)
  10.113495     UPS upsa at 192.168.10.200 is unavailable
  10.129095     Current power value: 1
  10.129095     Minimum power value: 1
  15.134979     pollups: upsa at 192.168.10.200
  15.134979     get_var: upsa at 192.168.10.200 / status
  15.134979     Poll UPS [upsa at 192.168.10.200] failed - Data stale
  15.150579     Current power value: 1
  15.150579     Minimum power value: 1
  20.158188     pollups: upsa at 192.168.10.200
  20.158188     get_var: upsa at 192.168.10.200 / status
  20.158188     Poll UPS [upsa at 192.168.10.200] failed - Data stale
   ...

But if the cable is already connected when the service is starting, it catches COMMBAD (cable disconnected) and COMMOK (cable connected again) notifications:

Network UPS Tools upsmon 2.6.5-3691:3709M
   0.000000     UPS: upsa at 192.168.10.200 (master) (power value 1)
   0.015600     Using power down flag file C:\killpower
   0.015600     debug level is '5'
   0.031200     Trying to connect to UPS [upsa at 192.168.10.200]
   0.031200     Login on UPS [upsa at 192.168.10.200] failed - got [ERR ACCESS-DENIED]
   0.046800     Current power value: 1
   0.046800     Minimum power value: 1
   5.054409     pollups: upsa at 192.168.10.200
   5.054409     get_var: upsa at 192.168.10.200 / status
   5.054409     parse_status: [OL]
   5.070009     parsing: [OL]
   5.070009     ups_on_line: upsa at 192.168.10.200 (first time)
   5.070009     Current power value: 1
   5.085609     Minimum power value: 1
  10.093218     pollups: upsa at 192.168.10.200
  10.093218     get_var: upsa at 192.168.10.200 / status
  10.093218     parse_status: [OL]
  10.108818     parsing: [OL]
  10.108818     ups_on_line: upsa at 192.168.10.200 (no change)
  10.108818     Current power value: 1
  10.124418     Minimum power value: 1
  15.125250     pollups: upsa at 192.168.10.200
  15.130250     get_var: upsa at 192.168.10.200 / status
  15.136251     parse_status: [OL]
  15.140251     parsing: [OL]
  15.143251     ups_on_line: upsa at 192.168.10.200 (no change)
  15.151252     Current power value: 1
  15.155252     Minimum power value: 1
  20.164865     pollups: upsa at 192.168.10.200
  20.164865     get_var: upsa at 192.168.10.200 / status
  20.164865     Poll UPS [upsa at 192.168.10.200] failed - Data stale
  20.180465     do_notify: ntype 0x0005 (COMMBAD)
  20.180465     Communications with UPS upsa at 192.168.10.200 lost
  20.196065     Current power value: 1
  20.196065     Minimum power value: 1
  25.211951     pollups: upsa at 192.168.10.200
  25.211951     get_var: upsa at 192.168.10.200 / status
  25.211951     Poll UPS [upsa at 192.168.10.200] failed - Data stale
  25.227551     Current power value: 1
  25.227551     Minimum power value: 1
  30.236992     pollups: upsa at 192.168.10.200
  30.236992     get_var: upsa at 192.168.10.200 / status
  30.236992     parse_status: [OL]
  30.252592     do_notify: ntype 0x0004 (COMMOK)
  30.252592     Communications with UPS upsa at 192.168.10.200 established
  30.268192     parsing: [OL]
  30.268192     ups_on_line: upsa at 192.168.10.200 (no change)
  30.268192     Current power value: 1
  30.268192     Minimum power value: 1
  ...

Operating system is Windows 7 x64.

I can send additional logs if it is necessary...

Thanks,
Denis