networkupstools
APC Back-UPS BX1600MI spurious LOWBATT/REPLACEBATT events
I have an APC Back-UPS BX1600MI connected by its supplied USB cable. With the version of nut in Debian 10 (2.7.4-8) this was unusable as the usbhid-ups driver kept disconnecting every few seconds.
I upgraded to the git HEAD of nut and communication is now stable, but spurious events come in every so often (once or twice an hour at the moment). When I call a notify script that calls upsc at the time of those spurious events, I can see that the ups.status does bear that out, but other values do not. Example:
battery.charge: 100
battery.charge.low: 10
battery.mfr.date: 2001/01/01
battery.runtime: 659
battery.runtime.low: 120
battery.type: PbAc
battery.voltage: 27.3
battery.voltage.nominal: 24.0
device.mfr: American Power Conversion
device.model: Back-UPS BX1600MI
device.serial: 9B2339A15993
device.type: ups
driver.debug: 0
driver.flag.allow_killpower: 0
driver.name: usbhid-ups
driver.parameter.poll freq: 30
driver.parameter.poll interval: 2
driver.parameter.port : auto
driver.parameter.synchronous: auto
driver.state: quiet
driver.version: 2.8.1-884-gf2fc47067
driver.version.data: APC HID 0.100
driver.version.internal: 0.52
driver.version.usb: libusb-1.0.22 (API: 0x1000106)
input.sensitivity: medium
input.transfer.high: 295
input.transfer.low: 145
input.voltage: 234.0
input.voltage.nominal: 230
ups.beeper.status: disabled
ups.delay.shutdown: 20
ups.load: 30
ups.mfr: American Power Conversion
ups.mfr.date: 2023/10/05
ups.model: Back-UPS BX1600MI
ups.productid: 0002
ups.realpower.nominal: 900
ups.serial: 9B2339A15993
ups.status: OL LB RB
ups.test.result: Done and passed
ups.timer.reboot: 0
ups.timer.shutdown: -1
ups.vendorid: 051d
As you can see, the status does include LB and RB, but the charge is still 100 and the runtime is as expected. The bad status lasts less than 2 seconds before returning to simply OL.
I went to the effort of installing a Windows VM, passing USB through to it and trying APC's own Powerchute Serial Shutdown software in there. This does not report any spurious events. Both Powerchute and nut do report true events that I induce. A self-test of the device passed. I am unable to demonstrate any behaviour that APC consider incorrect.
I returned the UPS to the vendor as faulty and they sent a replacement. The replacement behaves the same.
I installed apcupsd just to see how it behaved. It maintained a connection but its rate of spurious events was even worse: every couple of minutes. Another apcupsd user reports the same symptoms as me:
https://sourceforge.net/p/apcupsd/mailman/message/58740970/
Interestingly, they had a BX1600MI working with apcupsd, replaced it with another BX1600MI and now they see what I see, implying that newer models of Back-UPS have something different about them even though they are the same model number.
Is there any way to work around this sort of thing? It's almost like I need a way to not believe such statuses unless they persist for at least 5 seconds, or something.
I have a new APC Back-UPS BX1200MI (basically the same model, just with a smaller battery) connected to TrueNAS that is using NUT 2.8.0, and there is some weird behavior. Sometimes, 1-2 times an hour, it triggers LOWBATT/REPLACEBATT events. In a debug log, I can see "[D2] parse_status: [OL DISCHRG CHRG LB RB]".
When it works and the battery is charged to 100%, it seems to be switching between "OL" and "OL CHRG" every few seconds. I am not sure if that is a problem too, or just normal behavior, though.
Here is a debug log from the driver at the time of an event:
22097.933876 [D1] upsdrv_updateinfo...
22098.033355 [D2] file_report_buffer: expected 5 bytes, but got 512 instead
22098.033486 [D3] Report[err]: (512 bytes) => 16 63 82 42 fd 7f 00 00 01 00 00 00 00 00 00 00
22098.033549 [D3] 00 00 00 00 00 00 00 00 18 69 e6 c0 77 55 00 00 d5 ad e7 c0 77 55 00 00 80
22098.033611 [D3] b0 82 42 fd 7f 00 00 16 00 00 00 00 00 00 00 35 61 e6 c0 77 55 00 00 00 00
22098.033671 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 60 b2 82 42 fd 7f 00 00 90 b1 82
22098.033731 [D3] 42 fd 7f 00 00 2d 82 e5 65 00 00 00 00 b2 6c 00 00 00 00 00 00 02 00 00 00
22098.033792 [D3] 00 00 00 00 00 ba 75 9f c5 fe 19 5f ff ff ff ff 77 55 00 00 e0 b0 82 42 fd
22098.033856 [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 d2 1c 0f 3c 7f 00 00 00 00 00 00 00 00
22098.033917 [D3] 00 00 73 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ba 75 9f c5 fe 19
22098.033978 [D3] 5f 01 00 00 00 00 00 00 00 00 ba 75 9f c5 fe 19 5f 7b e7 e6 c0 77 55 00 00
22098.034038 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
22098.034099 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
22098.034159 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
22098.034219 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
22098.034280 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
22098.034340 [D3] 00 00 00 00 00 00 00 00 00 00 00 50 4f 4e 47 0a 00 00 00 00 ba 75 9f c5 fe
22098.034399 [D3] 19 5f 6d 6f 64 65 6c 73 20 28 d1 e4 e6 c0 77 55 00 00 da de 5d 00 00 00 00
22098.034459 [D3] 00 17 00 00 00 00 00 00 00 e0 af 82 42 fd 7f 00 00 00 00 00 00 00 00 00 00
22098.034519 [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 4a e8 c0 77 55 00 00 01
22098.034578 [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 b0 e5 c0 77 55 00 00 50 4f
22098.034638 [D3] 4e 47 0a 00 00 00 97 94 e6 c0 77 55 00 00 6d 6f 64 65 00 00 00 00 70 b2 82
22098.034696 [D3] 42 fd 7f 00 00 98 e4 e6 c0 77 55 00 00 01 00 00 00 00 00 00 00
22098.034751 [D1] Got 9 HID objects...
22098.034807 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.034862 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.034915 [D5] Unit = 00000000, UnitExp = 0
22098.034968 [D5] Exponent = 0
22098.035022 [D5] hid_lookup_path: 00840004 -> UPS
22098.035076 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.035130 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.035185 [D5] hid_lookup_path: 00850044 -> Charging
22098.035243 [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Input, ReportID: 0x16, Offset: 0, Size: 1, Value: 1
22098.035299 [D5] hu_find_infoval: found chrg (value: 1)
22098.035352 [D5] process_boolean_info: chrg
22098.035408 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.035462 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.035515 [D5] Unit = 00000000, UnitExp = 0
22098.035568 [D5] Exponent = 0
22098.035621 [D5] hid_lookup_path: 00840004 -> UPS
22098.035674 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.035727 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.035781 [D5] hid_lookup_path: 00850045 -> Discharging
22098.035836 [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Input, ReportID: 0x16, Offset: 1, Size: 1, Value: 1
22098.035891 [D5] hu_find_infoval: found dischrg (value: 1)
22098.035946 [D5] process_boolean_info: dischrg
22098.036001 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.036054 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.036107 [D5] Unit = 00000000, UnitExp = 0
22098.036159 [D5] Exponent = 0
22098.036212 [D5] hid_lookup_path: 00840004 -> UPS
22098.036265 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.036318 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.036372 [D5] hid_lookup_path: 008500d0 -> ACPresent
22098.036427 [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Input, ReportID: 0x16, Offset: 2, Size: 1, Value: 0
22098.036482 [D5] hu_find_infoval: found !online (value: 0)
22098.036534 [D5] process_boolean_info: !online
22098.036589 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.036642 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.036695 [D5] Unit = 00000000, UnitExp = 0
22098.036747 [D5] Exponent = 0
22098.036800 [D5] hid_lookup_path: 00840004 -> UPS
22098.036853 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.036906 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.036960 [D5] hid_lookup_path: 008500d1 -> BatteryPresent
22098.037014 [D2] Path: UPS.PowerSummary.PresentStatus.BatteryPresent, Type: Input, ReportID: 0x16, Offset: 3, Size: 1, Value: 0
22098.037067 [D5] hu_find_infoval: found nobattery (value: 0)
22098.037120 [D5] process_boolean_info: nobattery
22098.037238 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.037302 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.037354 [D5] Unit = 00000000, UnitExp = 0
22098.037407 [D5] Exponent = 0
22098.037460 [D5] hid_lookup_path: 00840004 -> UPS
22098.037513 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.037565 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.037618 [D5] hid_lookup_path: 00840069 -> ShutdownImminent
22098.037674 [D2] Path: UPS.PowerSummary.PresentStatus.ShutdownImminent, Type: Input, ReportID: 0x16, Offset: 4, Size: 1, Value: 0
22098.037728 [D5] hu_find_infoval: found !shutdownimm (value: 0)
22098.037780 [D5] process_boolean_info: !shutdownimm
22098.037835 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.037887 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.037939 [D5] Unit = 00000000, UnitExp = 0
22098.037991 [D5] Exponent = 0
22098.038043 [D5] hid_lookup_path: 00840004 -> UPS
22098.038095 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.038148 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.038201 [D5] hid_lookup_path: 00850043 -> RemainingTimeLimitExpired
22098.038255 [D2] Path: UPS.PowerSummary.PresentStatus.RemainingTimeLimitExpired, Type: Input, ReportID: 0x16, Offset: 5, Size: 1, Value: 1
22098.038309 [D5] hu_find_infoval: found timelimitexp (value: 1)
22098.038362 [D5] process_boolean_info: timelimitexp
22098.038416 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.038469 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.038522 [D5] Unit = 00000000, UnitExp = 0
22098.038573 [D5] Exponent = 0
22098.038625 [D5] hid_lookup_path: 00840004 -> UPS
22098.038679 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.038732 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.038785 [D5] hid_lookup_path: 0085004b -> NeedReplacement
22098.038844 [D2] Path: UPS.PowerSummary.PresentStatus.NeedReplacement, Type: Input, ReportID: 0x16, Offset: 6, Size: 1, Value: 1
22098.038855 [D5] hu_find_infoval: found replacebatt (value: 1)
22098.038867 [D5] process_boolean_info: replacebatt
22098.038879 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.038890 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.038902 [D5] Unit = 00000000, UnitExp = 0
22098.038916 [D5] Exponent = 0
22098.038928 [D5] hid_lookup_path: 00840004 -> UPS
22098.038939 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.038951 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.038962 [D5] hid_lookup_path: 00840065 -> Overload
22098.038974 [D2] Path: UPS.PowerSummary.PresentStatus.Overload, Type: Input, ReportID: 0x16, Offset: 7, Size: 1, Value: 0
22098.038986 [D5] hu_find_infoval: found !overload (value: 0)
22098.038998 [D5] process_boolean_info: !overload
22098.039010 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.039021 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.039033 [D5] Unit = 00000000, UnitExp = 0
22098.039044 [D5] Exponent = 0
22098.039056 [D5] hid_lookup_path: 00840004 -> UPS
22098.039067 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.039079 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.039090 [D5] hid_lookup_path: 008500db -> VoltageNotRegulated
22098.039105 [D2] Path: UPS.PowerSummary.PresentStatus.VoltageNotRegulated, Type: Input, ReportID: 0x16, Offset: 8, Size: 1, Value: 0
22098.039117 [D3] NUT doesn't use this HID object
22098.039130 [D1] Quick update...
22098.039141 [D4] Entering libusb_get_report
22098.039801 [D3] Report[get]: (3 bytes) => 15 ff ff
22098.039844 [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1
22098.039857 [D5] Unit = 00001001, UnitExp = 0
22098.039869 [D5] Exponent = 0
22098.039882 [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1
22098.039896 [D4] Entering libusb_get_report
22098.040607 [D3] Report[get]: (2 bytes) => 40 00
22098.040650 [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0
22098.040662 [D5] Unit = 00f0d121, UnitExp = 7
22098.040674 [D5] Exponent = 0
22098.040687 [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0
22098.040701 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.040716 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.040728 [D5] Unit = 00000000, UnitExp = 0
22098.040739 [D5] Exponent = 0
22098.040751 [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 0
22098.040763 [D5] hu_find_infoval: found !online (value: 0)
22098.040775 [D5] process_boolean_info: !online
22098.040787 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.040799 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.040810 [D5] Unit = 00000000, UnitExp = 0
22098.040822 [D5] Exponent = 0
22098.040834 [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 1
22098.040845 [D5] hu_find_infoval: found dischrg (value: 1)
22098.040857 [D5] process_boolean_info: dischrg
22098.040869 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.040880 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.040892 [D5] Unit = 00000000, UnitExp = 0
22098.040904 [D5] Exponent = 0
22098.040916 [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1
22098.040927 [D5] hu_find_infoval: found chrg (value: 1)
22098.040939 [D5] process_boolean_info: chrg
22098.040951 [D4] Entering libusb_get_report
22098.041601 [D3] Report[get]: (2 bytes) => 13 01
22098.041645 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.041658 [D5] Unit = 00001001, UnitExp = 0
22098.041670 [D5] Exponent = 0
22098.041683 [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1
22098.041695 [D5] hu_find_infoval: found online (value: 1)
22098.041707 [D5] process_boolean_info: online
22098.041719 [D4] Entering libusb_get_report
22098.042521 [D3] Report[get]: (2 bytes) => 14 00
22098.042563 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.042576 [D5] Unit = 00001001, UnitExp = 0
22098.042591 [D5] Exponent = 0
22098.042604 [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0
22098.042616 [D5] hu_find_infoval: found !lowbatt (value: 0)
22098.042628 [D5] process_boolean_info: !lowbatt
22098.042641 ups_status_set: seems that UPS [apc] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
22098.042656 [D5] send_to_all: SETINFO ups.status "OL DISCHRG CHRG LB RB"
22098.042679 [D6] send_to_all: write 43 bytes to socket 11 succeeded (ret=43): SETINFO ups.status "OL DISCHRG CHRG LB RB"
CC @desertwitch : I think some of your earlier investigations were about similar behavior; any ideas here?
Notably, in NUT master (after release 2.8.1) we had PR #2216 to address such notifications with new configuration options. From research and educated guesswork there, this may be just part of battery charge management which happens under the hood on all UPS devices that became visibly exposed on some firmwares.
It seems to be driver related, something that the UPS is sending byte-wise that's being parsed wrongly into RB (or LB)
22098.038416 [D3] Report[buf]: (5 bytes) => 16 63 82 42 fd
22098.038469 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0
22098.038522 [D5] Unit = 00000000, UnitExp = 0
22098.038573 [D5] Exponent = 0
22098.038625 [D5] hid_lookup_path: 00840004 -> UPS
22098.038679 [D5] hid_lookup_path: 00840024 -> PowerSummary
22098.038732 [D5] hid_lookup_path: 00840002 -> PresentStatus
22098.038785 [D5] hid_lookup_path: 0085004b -> NeedReplacement
22098.038844 [D2] Path: UPS.PowerSummary.PresentStatus.NeedReplacement, Type: Input, ReportID: 0x16, Offset: 6, Size: 1, Value: 1
22098.038855 [D5] hu_find_infoval: found replacebatt (value: 1)
22098.038867 [D5] process_boolean_info: replacebatt
I couldn't find an issue in the upsmon handling or the later processes. I've since had another report from a user with an APC BX1200MI-GR also experiencing intermediate OB and LB events, so it seems to be that series. I'm in touch with the other user if it works on an older version of NUT so hopefully we can narrow it down by comparison.
Got another report of an affected Back-UPS BX750MI with spurious RB events. I'm hoping to get my hands on at least a BX750MI this week so I can mess around with the driver a bit.
Unfortunately my contact didn't come through with the UPS so we're back to square one.
I am seeing the same behavior on a newly purchased BX1600MI-GR, manufactured October 2023, on Debian 12's nut 2.8.0-7.
@desertwitch let me know if I can help by collecting logs or trying any patches
Hello there! I'm using the containerized version of nut available in home assistant through the official add-on https://github.com/hassio-addons/addon-nut/tree/main
I can confirm this behavior is also happening with that version.
The pattern seems to be random. It sometimes lasts a few seconds or a few minutes.
I am seeing the same behavior on a newly purchased BX1600MI-GR, manufactured October 2023, on Debian 12's nut 2.8.0-7.
@desertwitch let me know if I can help by collecting logs or trying any patches
Any chance you could try if the problem persists putting this flag in your UPS configuration in UPS.CONF:
maxreport
So an example configuration would look like this:
[ups]
driver = usbhid-ups
port = auto
maxreport
Not sure if it will help or stop UPS recognition at all, but it's worth an attempt since we don't have an UPS at hand. I'm not an expert with the UPS drivers but if I can get my hands on an affected UPS eventually I hope to investigate more.
@jimklimov : Any ideas from the logs provided above? It's a relatively popular entry-level line of APC so would be lovely if we can get this addressed one way or another, I'm sure many people would be appreciative.
Same (or similar) issue here on a brand-new BX1600MI-GR. I'm not able to try NUT or get any logs right now, but the Unraid webgui/apcupsd shows the following:
The only thing that comes to mind is to add a yet another throttle for such reports - so to not expose the status if RB appears and dissipates quickly (I'd be wary of tweaking LB like that... maybe optionally-throttle tied to known OL/OB/BYPASS status?)
@desertwitch I just tried maxreport but it doesn't seem to help - I got two instances of "battery is low"+"battery needs to be replaced" within 3 hours.
@desertwitch I just tried
maxreportbut it doesn't seem to help - I got two instances of "battery is low"+"battery needs to be replaced" within 3 hours.
Thanks for the attempt, it was a long shot. Is there any logic to the statuses, as in do you notice a rapid succession or cycling through of certain statuses (LB, RB, LB, OL or OL, LB, RB, OL) in the logs? In general, do you have any logs you can provide us from NUT itself with timestamps so we can try to investigate this some more? Particularly interesting would be if the LB (low battery state) is happening at the same time the UPS is also OL or if the UPS goes OB before/after.
The only thing that comes to mind is to add a yet another throttle for such reports - so to not expose the status if RB appears and dissipates quickly (I'd be wary of tweaking LB like that... maybe optionally-throttle tied to known OL/OB/BYPASS status?)
I'm curious though if this is something else entirely, which the driver misinterprets, or the UPS is actually sending these bogus statuses. I'm thinking it probably doesn't happen on their own APC software (if there is one for that series) or there would be more complaints on the APC forums, but considering that both APCUPSD and NUT are affected in a similar fashion it does seem to be some change in the firmware when compared to other functioning APC devices or even older models of the BX series which seem to work (firmware bug in newer models?). I agree with a LB throttle on its own being less than ideal, unless tied to another status or succession of states (if we can figure out any logic behind what's happening here).
The general pattern is that it will usually be in a normal status like OL CHRG and then every hour or two I'll see this (on Debian 12, nut 2.8.0-7):
Apr 03 14:04:43 nut-ups-test usbhid-ups[556]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 03 14:04:44 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 03 14:04:44 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery needs to be replaced
Afterwards it goes back to a normal status.
I've been running a patched apcupsd so don't have any long-running logs at the moment, but I'm happy to run NUT as long as needed to help figure this out. Should I enable any debug logging options? Do you want me to try a fresh master build or should I keep using 2.8.0?
@desertwitch I just tried
maxreportbut it doesn't seem to help - I got two instances of "battery is low"+"battery needs to be replaced" within 3 hours.Thanks for the attempt, it was a long shot. Is there any logic to the statuses, as in do you notice a rapid succession or cycling through of certain statuses (LB, RB, LB, OL or OL, LB, RB, OL) in the logs? In general, do you have any logs you can provide us from NUT itself with timestamps so we can try to investigate this some more? Particularly interesting would be if the LB (low battery state) is happening at the same time the UPS is also OL or if the UPS goes OB before/after.
The only thing that comes to mind is to add a yet another throttle for such reports - so to not expose the status if RB appears and dissipates quickly (I'd be wary of tweaking LB like that... maybe optionally-throttle tied to known OL/OB/BYPASS status?)
I'm curious though if this is something else entirely, which the driver misinterprets, or the UPS is actually sending these bogus statuses. I'm thinking it probably doesn't happen on their own APC software (if there is one for that series) or there would be more complaints on the APC forums, but considering that both APCUPSD and NUT are affected in a similar fashion it does seem to be some change in the firmware when compared to other functioning APC devices or even older models of the BX series which seem to work (firmware bug in newer models?). I agree with a LB throttle on its own being less than ideal, unless tied to another status or succession of states (if we can figure out any logic behind what's happening here).
I just got home and installed NUT through the unraid apps. I'd be happy to provide logs but not sure how.
The general pattern is that it will usually be in a normal status like
OL CHRGand then every hour or two I'll see this (on Debian 12, nut 2.8.0-7):Apr 03 14:04:43 nut-ups-test usbhid-ups[556]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.Apr 03 14:04:44 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low Apr 03 14:04:44 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery needs to be replacedAfterwards it goes back to a normal status.
I've been running a patched
apcupsdso don't have any long-running logs at the moment, but I'm happy to run NUT as long as needed to help figure this out. Should I enable any debug logging options? Do you want me to try a freshmasterbuild or should I keep using 2.8.0?
This is very valuable, thanks, so we can also see here that the UPS is doing some kind of - perhaps - calibration before these LB and RB states. Maybe it is cycling through those states as part of some calibration mechanism. Can you confirm you're never having a "is on battery" status and you're also not getting any shutdowns from these intermittent low battery occurrences?
The other user's log above has shown a status of OL DISCHRG CHRG LB RB which seems to be also what's happening on your side (CHRG does not get reported in the logs as an event afaik). @jimklimov perhaps if users use the onlinedischarge_calibration to turn OL DISCHRG into CAL an additional toggle to basically ignore every other status while CAL is present could be a solution here (effectively suppressing CHRG LB RB in such cases and only reporting CAL)? We've already seen other UPS cycling through statuses while doing calibration, perhaps such a toggle could also be beneficial to other users? silent_calibration? But then the first user's status only shows OL LB RB, so that'd probably be useless in his case.
I upgraded to the git HEAD of nut and communication is now stable, but spurious events come in every so often (once or twice an hour at the moment). When I call a notify script that calls
upscat the time of those spurious events, I can see that theups.statusdoes bear that out, but other values do not. Example:battery.charge: 100 battery.charge.low: 10 battery.mfr.date: 2001/01/01 battery.runtime: 659 battery.runtime.low: 120 battery.type: PbAc battery.voltage: 27.3 battery.voltage.nominal: 24.0 device.mfr: American Power Conversion device.model: Back-UPS BX1600MI device.serial: 9B2339A15993 device.type: ups driver.debug: 0 driver.flag.allow_killpower: 0 driver.name: usbhid-ups driver.parameter.poll freq: 30 driver.parameter.poll interval: 2 driver.parameter.port : auto driver.parameter.synchronous: auto driver.state: quiet driver.version: 2.8.1-884-gf2fc47067 driver.version.data: APC HID 0.100 driver.version.internal: 0.52 driver.version.usb: libusb-1.0.22 (API: 0x1000106) input.sensitivity: medium input.transfer.high: 295 input.transfer.low: 145 input.voltage: 234.0 input.voltage.nominal: 230 ups.beeper.status: disabled ups.delay.shutdown: 20 ups.load: 30 ups.mfr: American Power Conversion ups.mfr.date: 2023/10/05 ups.model: Back-UPS BX1600MI ups.productid: 0002 ups.realpower.nominal: 900 ups.serial: 9B2339A15993 ups.status: OL LB RB ups.test.result: Done and passed ups.timer.reboot: 0 ups.timer.shutdown: -1 ups.vendorid: 051d
I don't know whether it is of any use, but I did not change anything (kept running git HEAD of nut) and the above strange behaviour stopped after a few days. It has been behaving normally for weeks now.
Thanks, Andy
@desertwitch I just tried
maxreportbut it doesn't seem to help - I got two instances of "battery is low"+"battery needs to be replaced" within 3 hours.Thanks for the attempt, it was a long shot. Is there any logic to the statuses, as in do you notice a rapid succession or cycling through of certain statuses (LB, RB, LB, OL or OL, LB, RB, OL) in the logs? In general, do you have any logs you can provide us from NUT itself with timestamps so we can try to investigate this some more? Particularly interesting would be if the LB (low battery state) is happening at the same time the UPS is also OL or if the UPS goes OB before/after.
The only thing that comes to mind is to add a yet another throttle for such reports - so to not expose the status if RB appears and dissipates quickly (I'd be wary of tweaking LB like that... maybe optionally-throttle tied to known OL/OB/BYPASS status?)
I'm curious though if this is something else entirely, which the driver misinterprets, or the UPS is actually sending these bogus statuses. I'm thinking it probably doesn't happen on their own APC software (if there is one for that series) or there would be more complaints on the APC forums, but considering that both APCUPSD and NUT are affected in a similar fashion it does seem to be some change in the firmware when compared to other functioning APC devices or even older models of the BX series which seem to work (firmware bug in newer models?). I agree with a LB throttle on its own being less than ideal, unless tied to another status or succession of states (if we can figure out any logic behind what's happening here).
I just got home and installed NUT through the unraid apps. I'd be happy to provide logs but not sure how.
Just set NUT up normally through the GUI as you want to have it and watch the SYSLOG (Tools->System Log) for any such strange events being reported while NUT is started, they should pop up in the log by themselves if they occur on your system.
I upgraded to the git HEAD of nut and communication is now stable, but spurious events come in every so often (once or twice an hour at the moment). When I call a notify script that calls
upscat the time of those spurious events, I can see that theups.statusdoes bear that out, but other values do not. Example:battery.charge: 100 battery.charge.low: 10 battery.mfr.date: 2001/01/01 battery.runtime: 659 battery.runtime.low: 120 battery.type: PbAc battery.voltage: 27.3 battery.voltage.nominal: 24.0 device.mfr: American Power Conversion device.model: Back-UPS BX1600MI device.serial: 9B2339A15993 device.type: ups driver.debug: 0 driver.flag.allow_killpower: 0 driver.name: usbhid-ups driver.parameter.poll freq: 30 driver.parameter.poll interval: 2 driver.parameter.port : auto driver.parameter.synchronous: auto driver.state: quiet driver.version: 2.8.1-884-gf2fc47067 driver.version.data: APC HID 0.100 driver.version.internal: 0.52 driver.version.usb: libusb-1.0.22 (API: 0x1000106) input.sensitivity: medium input.transfer.high: 295 input.transfer.low: 145 input.voltage: 234.0 input.voltage.nominal: 230 ups.beeper.status: disabled ups.delay.shutdown: 20 ups.load: 30 ups.mfr: American Power Conversion ups.mfr.date: 2023/10/05 ups.model: Back-UPS BX1600MI ups.productid: 0002 ups.realpower.nominal: 900 ups.serial: 9B2339A15993 ups.status: OL LB RB ups.test.result: Done and passed ups.timer.reboot: 0 ups.timer.shutdown: -1 ups.vendorid: 051dI don't know whether it is of any use, but I did not change anything (kept running git HEAD of nut) and the above strange behaviour stopped after a few days. It has been behaving normally for weeks now.
Thanks, Andy
That is extremely curious, makes me wonder even more what the UPS is doing there - thanks though!
Maybe it is cycling through those states as part of some calibration mechanism. Can you confirm you're never having a "is on battery" status and you're also not getting any shutdowns from these intermittent low battery occurrences?
Correct - I only get "is low" and "needs to be replaced".
As a sanity check, I just tried pulling the plug for a few seconds and this is what I see in that case:
Apr 03 15:56:06 nut-ups-test nut-monitor[579]: UPS apc1@localhost on battery
Apr 03 15:56:06 nut-ups-test nut-monitor[643]: Network UPS Tools upsmon 2.8.0
Apr 03 15:56:23 nut-ups-test usbhid-ups[574]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Som>
Apr 03 15:56:26 nut-ups-test nut-monitor[579]: UPS apc1@localhost on line power
Apr 03 15:56:26 nut-ups-test nut-monitor[646]: Network UPS Tools upsmon 2.8.0
Thanks for taking the time to look into this :)
Just set NUT up normally through the GUI as you want to have it and watch the SYSLOG (Tools->System Log) for any such strange events being reported while NUT is started, they should pop up in the log by themselves if they occur on your system.
Ah got it. They system log does show some low battery indications as seen below. I also pulled the plug and that all seems to be working fine. UPS switches to battery, I get an unraid notification, and it goes back online when plugged back in.
Apr 3 17:06:09 Tower upsmon[13245]: Startup successful
Apr 3 17:06:09 Tower upsmon[13245]: Warning: running as one big root process by request (upsmon -p)
Apr 3 17:06:09 Tower upsd[13239]: User [email protected] logged into UPS [ups]
Apr 3 17:07:14 Tower ool www[12635]: /usr/local/emhttp/plugins/nut-dw/scripts/write_config
Apr 3 17:07:14 Tower rc.nut: Writing NUT configuration...
Apr 3 17:07:15 Tower rc.nut: Updating permissions for NUT...
Apr 3 17:07:15 Tower rc.nut: Checking if the NUT Runtime Statistics Module should be enabled...
Apr 3 17:07:15 Tower rc.nut: Disabling the NUT Runtime Statistics Module...
...
Apr 3 17:14:09 Tower upsmon[13245]: UPS [email protected] battery is low
...
Apr 3 17:48:09 Tower upsmon[13245]: UPS [email protected] battery is low
...
Apr 3 18:09:34 Tower upsmon[13245]: UPS [email protected] on battery
Apr 3 18:09:34 Tower nut-notify: [ups] UPS is on battery. The system will shutdown when the UPS battery charge reaches 25%.
Apr 3 18:13:09 Tower upsmon[13245]: UPS [email protected] on line power
Apr 3 18:13:09 Tower nut-notify: [ups] UPS is online, any planned shutdowns were cancelled.
Thanks for the logs everyone - just to double-check back here, has anyone gotten a shutdown from this problem? Just to make sure that this is for the most part a log and notification problem and not an unwarranted shutdown one.
Thanks for the logs everyone - just to double-check back here, has anyone gotten a shutdown from this problem? Just to make sure that this is for the most part a log and notification problem and not an unwarranted shutdown one.
My unraid was running fine until I hooked it up to the UPS yesterday evening. This morning it was off, and it started a parity check after turning it on even though nothing was scheduled.
I can't find anything that logged the reason for the shutdown though. That was also using the built-in UPS utility, so I'll keep running NUT and see if I get any other unexpected shutdowns.
So I just checked the system log again and found a whole bunch of errors. Don't know if it's related. I left all configuration options on the default values except for the setting the shutdown rules to 25% battery left. I'm also running powertop --auto-tune in case that's relevant.
Apr 3 18:27:50 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error
Apr 3 18:27:52 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver!
Apr 3 18:41:45 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error
Apr 3 18:41:47 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver!
Apr 3 19:06:52 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error
Apr 3 19:06:54 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver!
Apr 3 19:29:52 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error
Apr 3 19:29:54 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver!
Apr 3 19:37:19 Tower upsmon[13245]: UPS [email protected] battery is low
Apr 3 19:38:58 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error
Apr 3 19:39:00 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver!
So I just checked the system log again and found a whole bunch of errors. Don't know if it's related. I left all configuration options on the default values except for the setting the shutdown rules to 25% battery left. I'm also running powertop --auto-tune in case that's relevant.
Apr 3 18:27:50 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error Apr 3 18:27:52 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver! Apr 3 18:41:45 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error Apr 3 18:41:47 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver! Apr 3 19:06:52 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error Apr 3 19:06:54 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver! Apr 3 19:29:52 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error Apr 3 19:29:54 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver! Apr 3 19:37:19 Tower upsmon[13245]: UPS [email protected] battery is low Apr 3 19:38:58 Tower usbhid-ups[13220]: nut_libusb_get_report: Input/Output Error Apr 3 19:39:00 Tower usbhid-ups[13220]: #012Reconnecting. If you saw "nut_libusb_get_interrupt: Input/Output Error" or similar message in the log above, try setting "pollonly" flag in "ups.conf" options section for this driver!
These messages are most certainly unrelated to the issue and often caused by powertop --auto-tune which is known to modify the USB power management settings and cause such input/output errors due to hibernating of USB-connected UPS devices at times when they should communicate with NUT. On Unraid in the NUT Settings there's a "USB Power Management Override" which you can test activating, it should stop these messages from appearing and prevent the UPS device from wrongful hibernations even in conjunction with powertop --auto-tune. This is also a very possible reason Unraid's in-built UPS Utility (which is apcupsd) caused the unwanted shutdown for you because it might not handle a non-communicating UPS well, particularly when in combination with simultaneous LOWBATT (low battery) states. Bottom line, best get rid of powertop --auto-tune if you can and set up your power saving settings in a way that do not interfere with the USB devices (or your UPS device at least).
Please continue to watch out for unwanted shutdowns now that you're using NUT and report back here if you should experience one which you suspect to be caused by this issue, for (unrelated) other issues surrounding the operation on Unraid you can also chime in at the respective support topic on the Unraid forums (so that we don't hijack this issue too much 😉).
Thanks for everyone's time and efforts helping investigate this issue so far. 💯
Same issue also with an APC Back-UPS BX1600MI i'm running NUT through the Unraid Plugin. Running the preview backend in said plugin as that already a different issue. (OL+DISCHRG messages issue Fixed here: #2261)
Can confirm that I haven't had an unwanted shutdown because of this error. Did have a power outage recently during which NUT was able to gracefully shut down my server.
Below I've attached a text file with the low battery and replace event's in my log since said power outage. NUT lowbatt & replace events.txt
In case these might be related I've also got 2 other issues:
- When first installing the UPS and after the power outage the battery charge percentage never reached 100%. It stays stuck at 99% rebooting NUT did not fix this issue. But after reinserting the USB cable NUT shows 100% battery charge. While stuck at 99% the UPS status is always OL CHRG but after reinserting the USB cable it's only OL.
- The runtime left value seems to only update 2-4 times a day (not sure if this is normal behaviour).
Following my previous comments (https://github.com/networkupstools/nut/issues/2347#issuecomment-2034878731 and https://github.com/networkupstools/nut/issues/2347#issuecomment-2035001225) I let this run for 24 hours and compiled my configs/logs below.
In short, I had 20 OL+DISCHRG warnings in the logs, of which:
- 1 immediately preceded simultaneous
battery is low+battery needs to be replaced - 9 immediately preceded
battery is low
No power failures, shutdowns or anything noteworthy happened during this time.
Environment and config
# lsb_release -a
No LSB modules are available.
Distributor ID: Debian
Description: Debian GNU/Linux 12 (bookworm)
Release: 12
Codename: bookworm
# uname -a
Linux nut-ups-test 6.1.0-18-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.76-1 (2024-02-01) x86_64 GNU/Linux
# grep -v "^#" /etc/nut/nut.conf | grep -v "^$"
MODE=standalone
# grep -v "^#" /etc/nut/ups.conf | grep -v "^$"
maxretry = 3
[apc1]
driver = usbhid-ups
port = auto
maxreport
# grep -v "^#" /etc/nut/upsd.conf | grep -v "^$"
LISTEN 127.0.0.1 3493
# grep -v "^#" /etc/nut/upsd.users | grep -v "^$"
[admin]
password = test1234
actions = SET
instcmds = ALL
[upsmon]
password = test1234
upsmon primary
# grep -v "^#" /etc/nut/upsmon.conf | grep -v "^$"
MONITOR apc1@localhost 1 upsmon test1234 primary
MINSUPPLIES 1
SHUTDOWNCMD "/sbin/shutdown -h +0"
POLLFREQ 5
POLLFREQALERT 5
HOSTSYNC 15
DEADTIME 15
POWERDOWNFLAG /etc/killpower
RBWARNTIME 43200
NOCOMMWARNTIME 300
FINALDELAY 5
# grep -v "^#" /etc/nut/upssched.conf | grep -v "^$"
CMDSCRIPT /bin/upssched-cmd
# upsc apc1@localhost
Init SSL without certificate database
battery.charge: 97
battery.charge.low: 10
battery.mfr.date: 2024/03/19
battery.runtime: 950
battery.runtime.low: 120
battery.type: PbAc
battery.voltage: 28.5
battery.voltage.nominal: 24.0
device.mfr: American Power Conversion
device.model: Back-UPS BX1600MI
device.serial: 9B2342A*****
device.type: ups
driver.flag.maxreport: enabled
driver.name: usbhid-ups
driver.parameter.pollfreq: 30
driver.parameter.pollinterval: 2
driver.parameter.port: auto
driver.parameter.synchronous: auto
driver.version: 2.8.0
driver.version.data: APC HID 0.98
driver.version.internal: 0.47
driver.version.usb: libusb-1.0.26 (API: 0x1000109)
input.sensitivity: medium
input.transfer.high: 295
input.transfer.low: 145
input.transfer.reason: input voltage out of range
input.voltage: 232.0
input.voltage.nominal: 230
ups.beeper.status: disabled
ups.delay.shutdown: 20
ups.load: 24
ups.mfr: American Power Conversion
ups.mfr.date: 2023/10/22
ups.model: Back-UPS BX1600MI
ups.productid: 0002
ups.realpower.nominal: 900
ups.serial: 9B2342A*****
ups.status: OL CHRG
ups.test.result: Done and passed
ups.timer.reboot: 0
ups.timer.shutdown: -1
ups.vendorid: 051d
Logs
24 hours without any power failures
Apr 03 19:27:02 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 03 19:27:02 nut-ups-test nut-monitor[729]: Network UPS Tools upsmon 2.8.0
Apr 03 19:53:33 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 03 20:04:09 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 03 22:17:22 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 03 22:17:23 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 03 22:17:23 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery needs to be replaced
Apr 03 22:17:23 nut-ups-test nut-monitor[743]: Network UPS Tools upsmon 2.8.0
Apr 03 22:17:23 nut-ups-test nut-monitor[744]: Network UPS Tools upsmon 2.8.0
Apr 03 22:56:23 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 03 22:56:23 nut-ups-test nut-monitor[752]: Network UPS Tools upsmon 2.8.0
Apr 03 23:21:34 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 00:44:35 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 01:37:49 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 01:37:50 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 01:37:50 nut-ups-test nut-monitor[889]: Network UPS Tools upsmon 2.8.0
Apr 04 01:54:08 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 01:54:10 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 01:54:10 nut-ups-test nut-monitor[903]: Network UPS Tools upsmon 2.8.0
Apr 04 02:26:35 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 02:26:35 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 02:26:35 nut-ups-test nut-monitor[908]: Network UPS Tools upsmon 2.8.0
Apr 04 03:51:35 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 03:51:36 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 03:51:36 nut-ups-test nut-monitor[1397]: Network UPS Tools upsmon 2.8.0
Apr 04 03:56:31 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 05:08:58 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 07:19:27 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 07:19:27 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 07:19:27 nut-ups-test nut-monitor[1513]: Network UPS Tools upsmon 2.8.0
Apr 04 07:27:37 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 07:50:11 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 07:50:12 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 07:50:12 nut-ups-test nut-monitor[1519]: Network UPS Tools upsmon 2.8.0
Apr 04 10:49:14 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 11:00:29 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 11:00:29 nut-ups-test nut-monitor[1702]: Network UPS Tools upsmon 2.8.0
Apr 04 11:13:35 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 11:31:01 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 11:45:56 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 13:36:27 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 15:27:06 nut-ups-test nut-monitor[560]: UPS apc1@localhost battery is low
Apr 04 15:27:06 nut-ups-test nut-monitor[1907]: Network UPS Tools upsmon 2.8.0
Apr 04 15:51:38 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Running a manual self-test by holding the button on the UPS
Apr 04 16:10:57 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 16:10:59 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 16:11:01 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 16:11:03 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Pulling the plug to simulate a power failure
Apr 04 16:12:21 nut-ups-test nut-monitor[560]: UPS apc1@localhost on battery
Apr 04 16:12:21 nut-ups-test nut-monitor[1918]: Network UPS Tools upsmon 2.8.0
Apr 04 16:12:35 nut-ups-test usbhid-ups[557]: ups_status_set: seems that UPS [apc1] is in OL+DISCHRG state now. Is it calibrating or do you perhaps want to set 'onlinedischarge' option? Some UPS models (e.g. CyberPower UT series) emit OL+DISCHRG when offline.
Apr 04 16:12:36 nut-ups-test nut-monitor[560]: UPS apc1@localhost on line power
Apr 04 16:12:36 nut-ups-test nut-monitor[1921]: Network UPS Tools upsmon 2.8.0
I have a new APC BX950MI and I am seeing the same as @ShiroDN noted, mainly around the switching between "OL" and "OL CHRG" every few seconds.
The strange thing is that I have the exact same model from about 2 years ago that doesn't output that switching behaviour, so it is probably something on the newer versions if the models.
I'd be happy to provide any information that you may require to help diagnose the issue, just let me know.
I have the same issue with BX2200MI, essentially the same ups with more capacity:
Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287346 [D3] Report[buf]: (2 bytes) => 18 01 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287365 [D5] PhyMax = 0, PhyMin = 0, LogMax = 3, LogMin = 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287382 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287395 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287410 [D2] Path: UPS.PowerSummary.AudibleAlarmControl, Type: Feature, ReportID: 0x18, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287422 [D3] Adding command 'beeper.enable' using Path 'UPS.PowerSummary.AudibleAlarmControl' Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287437 [D5] send_to_all: ADDCMD beeper.enable Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287452 [D5] hid_lookup_usage: UPS -> 00840004 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287467 [D5] hid_lookup_usage: PowerSummary -> 00840024 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287489 [D5] hid_lookup_usage: AudibleAlarmControl -> 0084005a Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287507 [D4] string_to_path: depth = 3 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287523 [D3] Report[buf]: (2 bytes) => 18 01 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287537 [D5] PhyMax = 0, PhyMin = 0, LogMax = 3, LogMin = 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287550 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287562 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287576 [D2] Path: UPS.PowerSummary.AudibleAlarmControl, Type: Feature, ReportID: 0x18, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287588 [D3] Adding command 'beeper.disable' using Path 'UPS.PowerSummary.AudibleAlarmControl' Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287607 [D5] send_to_all: ADDCMD beeper.disable Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287626 [D5] hid_lookup_usage: UPS -> 00840004 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287642 [D5] hid_lookup_usage: PowerSummary -> 00840024 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287658 [D5] hid_lookup_usage: AudibleAlarmControl -> 0084005a Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287670 [D4] string_to_path: depth = 3 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287687 [D3] Report[buf]: (2 bytes) => 18 01 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287701 [D5] PhyMax = 0, PhyMin = 0, LogMax = 3, LogMin = 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287713 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287730 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287748 [D2] Path: UPS.PowerSummary.AudibleAlarmControl, Type: Feature, ReportID: 0x18, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287761 [D3] Adding command 'beeper.mute' using Path 'UPS.PowerSummary.AudibleAlarmControl' Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287775 [D5] send_to_all: ADDCMD beeper.mute Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287798 [D5] send_to_all: ADDCMD load.off Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287816 [D2] find_nut_info: unknown info type: load.on.delay Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287838 [D2] find_nut_info: unknown info type: load.on.delay Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287859 [D5] send_to_all: SETINFO driver.version "2.8.0" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287880 [D5] send_to_all: SETINFO driver.version.internal "0.47" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287900 [D5] send_to_all: SETINFO driver.name "usbhid-ups" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287914 [D1] upsdrv_initinfo... Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287931 [D5] send_to_all: SETINFO driver.version.data "APC HID 0.98" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287948 [D5] send_to_all: SETINFO driver.parameter.pollfreq "30" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.287962 [D1] upsdrv_updateinfo... Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306759 [D2] file_report_buffer: expected 5 bytes, but got 512 instead Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306804 [D3] Report[err]: (512 bytes) => 16 0d 0d 01 fd 7f 00 00 d0 f2 92 aa 96 55 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306829 [D3] d0 bd 36 01 fd 7f 00 00 50 bf 36 01 fd 7f 00 00 1f c5 67 a9 96 55 00 00 fb Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306854 [D3] 9b 68 a9 96 55 00 00 00 00 00 00 00 00 00 00 04 71 67 a9 96 55 00 00 33 30 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306877 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306899 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306920 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306944 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306965 [D3] 00 00 00 32 3d 69 3f 12 64 6b 00 00 00 00 00 00 00 00 1f c5 67 a9 96 55 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.306986 [D3] 00 50 bf 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 c0 9c 4a ed 91 7f 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307008 [D3] 40 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307029 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307051 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307072 [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307093 [D3] 00 00 00 00 58 18 95 aa 96 55 00 00 00 32 3d 69 3f 12 64 6b d0 f2 92 aa 96 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307115 [D3] 55 00 00 58 18 95 aa 96 55 00 00 50 bf 36 01 fd 7f 00 00 00 32 3d 69 3f 12 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307136 [D3] 64 6b 1f c5 67 a9 96 55 00 00 50 bf 36 01 fd 7f 00 00 80 f0 3e 01 fd 7f 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307158 [D3] 00 90 95 66 a9 96 55 00 00 80 08 00 00 00 00 00 00 73 00 00 00 00 00 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307182 [D3] 00 00 00 00 00 00 00 00 18 00 00 00 30 00 00 00 30 c1 36 01 fd 7f 00 00 60 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307203 [D3] c0 36 01 fd 7f 00 00 33 30 00 20 48 49 44 20 30 2e 39 38 00 7f 00 00 75 73 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307225 [D3] 62 68 69 64 2d 75 70 73 00 00 00 00 00 00 62 79 74 65 73 29 20 3d 00 32 3d Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307265 [D3] 69 3f 12 64 6b 66 66 00 a9 96 55 00 00 0a f7 67 a9 96 55 00 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307283 [D1] Got 9 HID objects... Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307302 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307316 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307330 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307343 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307358 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307373 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307387 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307402 [D5] hid_lookup_path: 00850044 -> Charging Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307420 [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Input, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307437 [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307451 [D5] process_boolean_info: chrg Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307468 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307483 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307498 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307511 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307525 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307538 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307552 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307570 [D5] hid_lookup_path: 00850045 -> Discharging Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307587 [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Input, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307603 [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307624 [D5] process_boolean_info: !dischrg Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307640 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307655 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307668 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307680 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307693 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307707 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307722 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307740 [D5] hid_lookup_path: 008500d0 -> ACPresent Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307755 [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Input, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307770 [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307783 [D5] process_boolean_info: online Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307798 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307811 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307825 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307840 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307858 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307871 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307884 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307899 [D5] hid_lookup_path: 008500d1 -> BatteryPresent Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307915 [D2] Path: UPS.PowerSummary.PresentStatus.BatteryPresent, Type: Input, ReportID: 0x16, Offset: 3, Size: 1, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307929 [D5] hu_find_infoval: found !nobattery (value: 1) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307942 [D5] process_boolean_info: !nobattery Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307960 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307982 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.307995 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308007 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308020 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308034 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308048 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308062 [D5] hid_lookup_path: 00840069 -> ShutdownImminent Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308077 [D2] Path: UPS.PowerSummary.PresentStatus.ShutdownImminent, Type: Input, ReportID: 0x16, Offset: 4, Size: 1, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308099 [D5] hu_find_infoval: found !shutdownimm (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308112 [D5] process_boolean_info: !shutdownimm Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308129 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308141 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308154 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308166 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308179 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308192 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308213 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308227 [D5] hid_lookup_path: 00850043 -> RemainingTimeLimitExpired Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308241 [D2] Path: UPS.PowerSummary.PresentStatus.RemainingTimeLimitExpired, Type: Input, ReportID: 0x16, Offset: 5, Size: 1, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308255 [D5] hu_find_infoval: found !timelimitexp (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308268 [D5] process_boolean_info: !timelimitexp Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308284 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308297 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308311 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308331 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308344 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308358 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308371 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308386 [D5] hid_lookup_path: 0085004b -> NeedReplacement Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308401 [D2] Path: UPS.PowerSummary.PresentStatus.NeedReplacement, Type: Input, ReportID: 0x16, Offset: 6, Size: 1, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308415 [D5] hu_find_infoval: found !replacebatt (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308428 [D5] process_boolean_info: !replacebatt Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308452 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308466 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308478 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308490 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308504 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308518 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308532 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308545 [D5] hid_lookup_path: 00840065 -> Overload Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308568 [D2] Path: UPS.PowerSummary.PresentStatus.Overload, Type: Input, ReportID: 0x16, Offset: 7, Size: 1, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308583 [D5] hu_find_infoval: found !overload (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308596 [D5] process_boolean_info: !overload Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308612 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308624 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308637 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308650 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308663 [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308688 [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308703 [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308717 [D5] hid_lookup_path: 008500db -> VoltageNotRegulated Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308733 [D2] Path: UPS.PowerSummary.PresentStatus.VoltageNotRegulated, Type: Input, ReportID: 0x16, Offset: 8, Size: 1, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308747 [D3] NUT doesn't use this HID object Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308764 [D1] Quick update... Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308780 [D3] Report[buf]: (3 bytes) => 15 ff ff Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308794 [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308816 [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308829 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308843 [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308863 [D3] Report[buf]: (2 bytes) => 40 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308877 [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308935 [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308948 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308962 [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.308989 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309002 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309015 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309028 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309042 [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309055 [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309067 [D5] process_boolean_info: online Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309082 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309103 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309115 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309127 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309143 [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309155 [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309168 [D5] process_boolean_info: !dischrg Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309183 [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309196 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309217 [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309229 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309243 [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309256 [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309269 [D5] process_boolean_info: chrg Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309284 [D3] Report[buf]: (2 bytes) => 13 01 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309297 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309309 [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309329 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309345 [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309358 [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309370 [D5] process_boolean_info: online Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309384 [D3] Report[buf]: (2 bytes) => 14 00 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309397 [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309410 [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309422 [D5] Exponent = 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309447 [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309460 [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309473 [D5] process_boolean_info: !lowbatt Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309498 [D5] send_to_all: SETINFO input.transfer.reason "input voltage out of range" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309516 [D5] send_to_all: SETINFO ups.status "OL CHRG" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309531 [D5] send_to_all: DATAOK Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309647 [D2] dstate_init: sock /run/nut/usbhid-ups-BX2200MI open on fd 9 Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309668 [D5] send_to_all: SETINFO driver.parameter.pollinterval "2" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309696 [D5] send_to_all: SETINFO driver.parameter.synchronous "auto" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309716 [D5] send_to_all: SETINFO device.mfr "American Power Conversion" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309733 [D5] send_to_all: SETINFO device.model "Back-UPS BX2200MI" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386987]: 0.309751 [D5] send_to_all: SETINFO device.serial "XXXXXXXXXXXX" Apr 21 10:52:41 localhost nut-driver@BX2200MI[1386986]: Network UPS Tools - UPS driver controller 2.8.0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 2 bytes, but got 512 instead Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 0c 63 36 01 fd 7f 00 00 77 b8 67 a9 96 55 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] c0 bd 36 01 fd 7f 00 00 40 8a 95 aa 96 55 00 00 e0 bd 36 01 fd 7f 00 00 94 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] ef 3c ed 91 7f 00 00 8f 76 68 a9 96 55 00 00 fb 9b 68 a9 0a 00 00 00 10 31 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 33 38 37 30 30 37 00 32 3d 69 3f 12 64 6b f0 bd 36 01 fd 7f 00 00 db d6 36 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] ed 91 7f 00 00 ff 29 15 00 00 00 00 00 00 bf 36 01 fd 7f 00 00 07 00 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 94 f2 3c ed 91 7f 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 12 64 6b f0 32 93 aa 96 55 00 00 78 ff ff ff ff ff ff ff 02 00 00 00 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 fb 9b 68 a9 96 55 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 8f fe 36 ed 91 7f 00 00 68 0d 00 00 00 00 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 32 3d 69 3f 12 64 6b 00 00 00 00 91 7f 00 00 78 ff ff ff ff ff ff ff 02 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 e0 65 4a ed 91 7f 00 00 07 00 00 00 00 00 00 00 fb 9b Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 68 a9 96 55 00 00 00 00 00 00 00 00 00 00 8f fe 36 ed 91 7f 00 00 07 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 74 88 35 ed 91 7f 00 00 70 9f 92 aa 96 55 00 00 70 9f 92 aa Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 96 55 00 00 00 00 00 00 00 00 00 00 a3 ca 34 ed 91 7f 00 00 70 9f 92 aa 96 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 55 00 00 ff 29 15 00 00 00 00 00 00 bf 36 01 fd 7f 00 00 00 32 3d 69 3f 12 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 64 6b 2f 72 75 6e 2f 6e 75 74 2f 75 73 62 68 69 64 2d d6 d3 eb 09 00 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 07 00 00 00 00 00 00 00 70 9f 92 aa 96 55 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 74 41 67 a9 96 55 00 00 48 2a 69 a9 96 55 00 00 18 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 30 00 00 00 40 c1 36 01 fd 7f 00 00 70 c0 36 01 fd 7f 00 00 39 42 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 32 33 34 35 41 33 38 36 36 30 00 30 30 4d 49 00 76 65 72 73 69 6f 6e 00 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D1] Got 1 HID objects... Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 0c 63 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850066 -> RemainingCapacity Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.RemainingCapacity, Type: Input, ReportID: 0x0c, Offset: 0, Size: 8, Value: 99 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (3 bytes) => 15 ff ff Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 40 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 13 01 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 14 00 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:41 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:42 localhost usbhid-ups[1387007]: sock_connect: enabling asynchronous mode (auto) Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D3] new connection on fd 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.charge "99" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.charge "99" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.charge.low "10" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.charge.low "10" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX battery.charge.low 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX battery.charge.low 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS battery.charge.low RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS battery.charge.low RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.mfr.date "2024/04/04" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.mfr.date "2024/04/04" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX battery.mfr.date 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX battery.mfr.date 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS battery.mfr.date RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS battery.mfr.date RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.runtime "3777" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.runtime "3777" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.runtime.low "120" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.runtime.low "120" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX battery.runtime.low 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX battery.runtime.low 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS battery.runtime.low RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS battery.runtime.low RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.type "PbAc" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.type "PbAc" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.voltage "27.2" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.voltage "27.2" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO battery.voltage.nominal "24.0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO battery.voltage.nominal "24.0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO device.mfr "American Power Conversion" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO device.mfr "American Power Conversion" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO device.model "Back-UPS BX2200MI" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO device.model "Back-UPS BX2200MI" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO device.serial "XXXXXXXXXXXX" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO device.serial "XXXXXXXXXXXX" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO device.type "ups" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO device.type "ups" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.name "usbhid-ups" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.name "usbhid-ups" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.parameter.pollfreq "30" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.parameter.pollfreq "30" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.parameter.pollinterval "2" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.parameter.pollinterval "2" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.parameter.port "auto" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.parameter.port "auto" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.parameter.synchronous "auto" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.parameter.synchronous "auto" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.parameter.vendorid "051d" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.parameter.vendorid "051d" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.version "2.8.0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.version "2.8.0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.version.data "APC HID 0.98" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.version.data "APC HID 0.98" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.version.internal "0.47" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.version.internal "0.47" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO driver.version.usb "libusb-1.0.26 (API: 0x1000109)" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x1000109)" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO input.sensitivity "low" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO input.sensitivity "low" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX input.sensitivity 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX input.sensitivity 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS input.sensitivity RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS input.sensitivity RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO input.transfer.high "295" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO input.transfer.high "295" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX input.transfer.high 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX input.transfer.high 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS input.transfer.high RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS input.transfer.high RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO input.transfer.low "145" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO input.transfer.low "145" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX input.transfer.low 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX input.transfer.low 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS input.transfer.low RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS input.transfer.low RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO input.transfer.reason "input voltage out of range" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO input.transfer.reason "input voltage out of range" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO input.voltage "221.0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO input.voltage "221.0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO input.voltage.nominal "230" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO input.voltage.nominal "230" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.beeper.status "disabled" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.beeper.status "disabled" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.delay.shutdown "20" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.delay.shutdown "20" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETAUX ups.delay.shutdown 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETAUX ups.delay.shutdown 10 Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETFLAGS ups.delay.shutdown RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETFLAGS ups.delay.shutdown RW STRING Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.load "0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.load "0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.mfr "American Power Conversion" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.mfr "American Power Conversion" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.mfr.date "2023/11/09" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.mfr.date "2023/11/09" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.model "Back-UPS BX2200MI" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.model "Back-UPS BX2200MI" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.productid "0002" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.productid "0002" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.realpower.nominal "1200" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.realpower.nominal "1200" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.serial "XXXXXXXXXXXX" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.serial "XXXXXXXXXXXX" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.status "OL CHRG" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.status "OL CHRG" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.test.result "Aborted" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.test.result "Aborted" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.timer.reboot "0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.timer.reboot "0" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.timer.shutdown "-1" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.timer.shutdown "-1" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending SETINFO ups.vendorid "051d" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: SETINFO ups.vendorid "051d" Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD beeper.disable Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD beeper.disable Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD beeper.enable Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD beeper.enable Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD beeper.mute Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD beeper.mute Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD beeper.off Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD beeper.off Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD beeper.on Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD beeper.on Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD load.off Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD load.off Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD load.off.delay Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD load.off.delay Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD shutdown.reboot Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD shutdown.reboot Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD shutdown.stop Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD shutdown.stop Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD test.battery.start.deep Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD test.battery.start.deep Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD test.battery.start.quick Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD test.battery.start.quick Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending ADDCMD test.battery.stop Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: ADDCMD test.battery.stop Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending DATAOK Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: DATAOK Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D2] send_to_one: sending DUMPDONE Apr 21 10:52:42 localhost usbhid-ups[1387007]: [D5] send_to_one: DUMPDONE Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D1] upsdrv_updateinfo... Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 2 bytes, but got 512 instead Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 0c 63 36 01 fd 7f 00 00 01 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 18 49 67 a9 96 55 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 ff ff ff ff 40 9a 94 aa 96 55 00 00 35 41 67 a9 96 55 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d0 bf 36 01 fd 7f 00 00 00 bf 36 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 01 02 00 00 00 ca c5 24 66 00 00 00 00 72 9a 0b 00 00 00 00 00 01 00 6f 66 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 20 72 61 6e 67 65 00 69 3f 12 64 6b ff ff ff ff 00 00 00 00 50 be 36 01 fd Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 62 4a ed 91 7f 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 6b 01 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b 7b c7 67 a9 96 55 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 44 55 4d 50 41 4c 4c 0a 00 32 3d 69 3f 12 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 64 6b 99 99 99 99 99 99 99 99 d1 c4 67 a9 96 55 00 00 3a d6 eb 09 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 50 bd 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 2a 69 a9 96 55 00 00 01 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 90 66 a9 96 55 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 14 00 00 00 96 55 00 00 7a 65 3a 20 00 00 00 00 00 00 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D1] Got 1 HID objects... Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 0c 63 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850066 -> RemainingCapacity Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.RemainingCapacity, Type: Input, ReportID: 0x0c, Offset: 0, Size: 8, Value: 99 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[get]: (3 bytes) => 15 ff ff Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 40 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] refresh_report_buffer: expected 5 bytes, but got 3 instead Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[err]: (3 bytes) => 16 0d 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 13 01 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 14 00 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:43 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D1] upsdrv_updateinfo... Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 5 bytes, but got 512 instead Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 16 0d 0d 01 fd 7f 00 00 01 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 18 49 67 a9 96 55 00 00 d5 8d 68 a9 96 55 00 00 f0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] bd 36 01 fd 7f 00 00 0c 00 00 00 00 00 00 00 35 41 67 a9 96 55 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d0 bf 36 01 fd 7f 00 00 00 bf 36 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 01 fd 7f 00 00 cb c5 24 66 00 00 00 00 30 1b 0c 00 00 00 00 00 74 20 6f 66 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 20 72 61 6e 67 65 00 69 3f 12 64 6b ff ff ff ff 96 55 00 00 50 be 36 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 62 4a ed 91 7f 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 6b 01 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b 7b c7 67 a9 96 55 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 ff 00 00 00 00 00 00 00 00 32 3d 69 3f 12 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 64 6b 99 99 99 99 99 99 99 99 d1 c4 67 a9 96 55 00 00 5c d8 eb 09 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 50 bd 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 2a 69 a9 96 55 00 00 01 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 90 66 a9 96 55 00 00 20 20 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 20 20 20 20 20 20 14 00 00 00 20 20 20 20 7a 65 3a 20 00 00 00 00 00 00 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D1] Got 9 HID objects... Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850044 -> Charging Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Input, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850045 -> Discharging Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Input, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 008500d0 -> ACPresent Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Input, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 008500d1 -> BatteryPresent Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.BatteryPresent, Type: Input, ReportID: 0x16, Offset: 3, Size: 1, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !nobattery (value: 1) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !nobattery Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840069 -> ShutdownImminent Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ShutdownImminent, Type: Input, ReportID: 0x16, Offset: 4, Size: 1, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !shutdownimm (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !shutdownimm Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850043 -> RemainingTimeLimitExpired Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.RemainingTimeLimitExpired, Type: Input, ReportID: 0x16, Offset: 5, Size: 1, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !timelimitexp (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !timelimitexp Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 0085004b -> NeedReplacement Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.NeedReplacement, Type: Input, ReportID: 0x16, Offset: 6, Size: 1, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !replacebatt (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !replacebatt Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840065 -> Overload Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Overload, Type: Input, ReportID: 0x16, Offset: 7, Size: 1, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !overload (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !overload Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 008500db -> VoltageNotRegulated Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.VoltageNotRegulated, Type: Input, ReportID: 0x16, Offset: 8, Size: 1, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] NUT doesn't use this HID object Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[get]: (3 bytes) => 15 ff ff Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 40 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 13 01 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 14 00 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:45 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D1] upsdrv_updateinfo... Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 2 bytes, but got 512 instead Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 14 00 36 01 fd 7f 00 00 01 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 18 49 67 a9 96 55 00 00 d5 8d 68 a9 96 55 00 00 f0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] bd 36 01 fd 7f 00 00 16 00 00 00 00 00 00 00 35 41 67 a9 96 55 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d0 bf 36 01 fd 7f 00 00 00 bf 36 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 01 fd 7f 00 00 cd c5 24 66 00 00 00 00 d0 b8 0b 00 00 00 00 00 74 20 6f 66 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 20 72 61 6e 67 65 00 69 3f 12 64 6b ff ff ff ff 96 55 00 00 50 be 36 01 fd Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 62 4a ed 91 7f 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 6b 01 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b 7b c7 67 a9 96 55 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 ff 00 00 00 00 00 00 00 00 32 3d 69 3f 12 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 64 6b 99 99 99 99 99 99 99 99 d1 c4 67 a9 96 55 00 00 d0 da eb 09 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 50 bd 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 2a 69 a9 96 55 00 00 01 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 90 66 a9 96 55 00 00 ff 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 14 00 00 00 00 00 00 00 99 99 99 99 00 00 00 00 00 00 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D1] Got 1 HID objects... Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 14 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850042 -> BelowRemainingCapacityLimit Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Input, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[get]: (3 bytes) => 15 ff ff Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 40 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] refresh_report_buffer: expected 5 bytes, but got 3 instead Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[err]: (3 bytes) => 16 0d 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 13 01 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 14 00 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:47 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D1] upsdrv_updateinfo... Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 2 bytes, but got 512 instead Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 0c 63 36 01 fd 7f 00 00 01 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 18 49 67 a9 96 55 00 00 d5 8d 68 a9 96 55 00 00 f0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] bd 36 01 fd 7f 00 00 14 00 00 00 00 00 00 00 35 41 67 a9 96 55 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d0 bf 36 01 fd 7f 00 00 00 bf 36 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 01 fd 7f 00 00 cf c5 24 66 00 00 00 00 f2 72 0b 00 00 00 00 00 74 20 6f 66 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 20 72 61 6e 67 65 00 69 3f 12 64 6b ff ff ff ff 96 55 00 00 50 be 36 01 fd Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 62 4a ed 91 7f 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 6b 01 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b 7b c7 67 a9 96 55 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 ff 00 00 00 00 00 00 00 00 32 3d 69 3f 12 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 64 6b 99 99 99 99 99 99 99 99 d1 c4 67 a9 96 55 00 00 06 dd eb 09 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 50 bd 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 2a 69 a9 96 55 00 00 01 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 90 66 a9 96 55 00 00 ff 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 14 00 00 00 00 00 00 00 99 99 99 99 00 00 00 00 00 00 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D1] Got 1 HID objects... Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 0c 63 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850066 -> RemainingCapacity Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.RemainingCapacity, Type: Input, ReportID: 0x0c, Offset: 0, Size: 8, Value: 99 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[get]: (3 bytes) => 15 ff ff Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 40 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] refresh_report_buffer: expected 5 bytes, but got 3 instead Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[err]: (3 bytes) => 16 0d 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 13 01 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 14 00 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:49 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:50 localhost usbhid-ups[1387007]: [D2] send_to_one: sending PONG Apr 21 10:52:50 localhost usbhid-ups[1387007]: [D5] send_to_one: PONG Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D1] upsdrv_updateinfo... Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 2 bytes, but got 512 instead Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 0c 63 36 01 fd 7f 00 00 01 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 18 49 67 a9 96 55 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 ff ff ff ff 40 9a 94 aa 96 55 00 00 35 41 67 a9 96 55 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d0 bf 36 01 fd 7f 00 00 00 bf 36 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 01 fd 7f 00 00 d2 c5 24 66 00 00 00 00 79 1a 0b 00 00 00 00 00 74 20 6f 66 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 20 72 61 6e 67 65 00 69 3f 12 64 6b ff ff ff ff 96 55 00 00 50 be 36 01 fd Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 62 4a ed 91 7f 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 6b 01 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b 7b c7 67 a9 96 55 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 50 49 4e 47 0a 00 00 00 00 32 3d 69 3f 12 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 64 6b 99 99 99 99 99 99 99 99 d1 c4 67 a9 96 55 00 00 44 df eb 09 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 50 bd 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 2a 69 a9 96 55 00 00 01 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 90 66 a9 96 55 00 00 20 20 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 20 20 20 20 20 20 14 00 00 00 20 20 20 20 7a 65 3a 20 00 00 00 00 00 00 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D1] Got 1 HID objects... Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[buf]: (2 bytes) => 0c 63 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850066 -> RemainingCapacity Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.RemainingCapacity, Type: Input, ReportID: 0x0c, Offset: 0, Size: 8, Value: 99 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[get]: (3 bytes) => 15 ff ff Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 40 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] refresh_report_buffer: expected 5 bytes, but got 3 instead Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[err]: (3 bytes) => 16 0d 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 00 01 fd Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 13 01 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 14 00 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:51 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D1] upsdrv_updateinfo... Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] file_report_buffer: expected 5 bytes, but got 512 instead Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[err]: (512 bytes) => 16 0d 0d 01 fd 7f 00 00 01 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 18 49 67 a9 96 55 00 00 d5 8d 68 a9 96 55 00 00 f0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] bd 36 01 fd 7f 00 00 0c 00 00 00 00 00 00 00 35 41 67 a9 96 55 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d0 bf 36 01 fd 7f 00 00 00 bf 36 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 01 fd 7f 00 00 d3 c5 24 66 00 00 00 00 1c e3 0a 00 00 00 00 00 74 20 6f 66 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 20 72 61 6e 67 65 00 69 3f 12 64 6b ff ff ff ff 96 55 00 00 50 be 36 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 7f 00 00 00 00 00 00 00 00 00 00 e0 62 4a ed 91 7f 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 6b 01 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b 7b c7 67 a9 96 55 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 ff 00 00 00 00 00 00 00 00 32 3d 69 3f 12 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 64 6b 99 99 99 99 99 99 99 99 d1 c4 67 a9 96 55 00 00 92 e1 eb 09 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 18 00 00 00 00 00 00 00 50 bd 36 01 fd 7f 00 00 00 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 48 2a 69 a9 96 55 00 00 01 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 1d 05 00 00 00 00 00 00 99 90 66 a9 96 55 00 00 20 20 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 20 20 20 20 20 20 14 00 00 00 20 20 20 20 7a 65 3a 20 00 00 00 00 00 00 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 32 3d 69 3f 12 64 6b Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D1] Got 9 HID objects... Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850044 -> Charging Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Input, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850045 -> Discharging Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Input, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 008500d0 -> ACPresent Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Input, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 008500d1 -> BatteryPresent Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.BatteryPresent, Type: Input, ReportID: 0x16, Offset: 3, Size: 1, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !nobattery (value: 1) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !nobattery Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840069 -> ShutdownImminent Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ShutdownImminent, Type: Input, ReportID: 0x16, Offset: 4, Size: 1, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !shutdownimm (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !shutdownimm Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00850043 -> RemainingTimeLimitExpired Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.RemainingTimeLimitExpired, Type: Input, ReportID: 0x16, Offset: 5, Size: 1, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !timelimitexp (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !timelimitexp Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 0085004b -> NeedReplacement Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.NeedReplacement, Type: Input, ReportID: 0x16, Offset: 6, Size: 1, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !replacebatt (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !replacebatt Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840065 -> Overload Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Overload, Type: Input, ReportID: 0x16, Offset: 7, Size: 1, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !overload (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !overload Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840004 -> UPS Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840024 -> PowerSummary Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 00840002 -> PresentStatus Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hid_lookup_path: 008500db -> VoltageNotRegulated Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.VoltageNotRegulated, Type: Input, ReportID: 0x16, Offset: 8, Size: 1, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] NUT doesn't use this HID object Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D1] Quick update... Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[get]: (3 bytes) => 15 ff ff Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 32767, LogMin = -1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.DelayBeforeShutdown, Type: Feature, ReportID: 0x15, Offset: 0, Size: 16, Value: -1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 40 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 255, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00f0d121, UnitExp = 7 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.APCGeneralCollection.APCDelayBeforeReboot, Type: Feature, ReportID: 0x40, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.ACPresent, Type: Feature, ReportID: 0x16, Offset: 2, Size: 1, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Discharging, Type: Feature, ReportID: 0x16, Offset: 1, Size: 1, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !dischrg (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !dischrg Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[buf]: (5 bytes) => 16 0d 0d 01 fd Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00000000, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.PresentStatus.Charging, Type: Feature, ReportID: 0x16, Offset: 0, Size: 1, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found chrg (value: 1) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: chrg Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 13 01 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.ACPresent, Type: Feature, ReportID: 0x13, Offset: 0, Size: 8, Value: 1 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found online (value: 1) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: online Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D4] Entering libusb_get_report Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D3] Report[get]: (2 bytes) => 14 00 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] PhyMax = 0, PhyMin = 0, LogMax = 1, LogMin = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Unit = 00001001, UnitExp = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] Exponent = 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D2] Path: UPS.PowerSummary.BelowRemainingCapacityLimit, Type: Feature, ReportID: 0x14, Offset: 0, Size: 8, Value: 0 Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] hu_find_infoval: found !lowbatt (value: 0) Apr 21 10:52:53 localhost usbhid-ups[1387007]: [D5] process_boolean_info: !lowbatt
and the upc output (Network UPS Tools upsc 2.8.0):
upsc BX2200MI@localhost Init SSL without certificate database battery.charge: 99 battery.charge.low: 10 battery.mfr.date: 2024/04/04 battery.runtime: 3777 battery.runtime.low: 120 battery.type: PbAc battery.voltage: 27.2 battery.voltage.nominal: 24.0 device.mfr: American Power Conversion device.model: Back-UPS BX2200MI device.serial: XXXXXXXXXXXX device.type: ups driver.name: usbhid-ups driver.parameter.pollfreq: 30 driver.parameter.pollinterval: 2 driver.parameter.port: auto driver.parameter.synchronous: auto driver.parameter.vendorid: 051d driver.version: 2.8.0 driver.version.data: APC HID 0.98 driver.version.internal: 0.47 driver.version.usb: libusb-1.0.26 (API: 0x1000109) input.sensitivity: low input.transfer.high: 295 input.transfer.low: 145 input.transfer.reason: input voltage out of range input.voltage: 224.0 input.voltage.nominal: 230 ups.beeper.status: disabled ups.delay.shutdown: 20 ups.load: 0 ups.mfr: American Power Conversion ups.mfr.date: 2023/11/09 ups.model: Back-UPS BX2200MI ups.productid: 0002 ups.realpower.nominal: 1200 ups.serial: XXXXXXXXXXXX ups.status: OL CHRG ups.test.result: Aborted ups.timer.reboot: 0 ups.timer.shutdown: -1 ups.vendorid: 051d