networkupstools / nut

The Network UPS Tools repository. UPS management protocol Informational RFC 9271 published by IETF at https://www.rfc-editor.org/info/rfc9271 Please star NUT on GitHub, this helps with sponsorships!
https://networkupstools.org/
Other
1.99k stars 349 forks source link

Salicru SLC 3000 Twin RT2 not supported by usbhid-ups #1437

Open fsckawk opened 2 years ago

fsckawk commented 2 years ago

My setup:

Output from "upsc":

device.mfr: Salicru
device.model: TWIN3000-R
device.serial: 0000000000000000
device.type: ups
driver.name: usbhid-ups
driver.parameter.pollfreq: 30
driver.parameter.pollinterval: 2
driver.parameter.port: auto
driver.parameter.productid: 0102
driver.parameter.synchronous: auto
driver.parameter.vendorid: 2e66
driver.version: 2.8.0-98-g3744cb047
driver.version.data: Salicru HID 0.3
driver.version.internal: 0.48
driver.version.usb: libusb-1.0.23 (API: 0x1000107)
ups.mfr: Salicru
ups.model: TWIN3000-R
ups.productid: 0102
ups.serial: 0000000000000000
ups.status: OB
ups.vendorid: 2e66

Output from "/usr/local/ups/bin/usbhid-ups -D -u root -a upsslc":

Network UPS Tools - Generic HID driver 0.48 (2.8.0-98-g3744cb047)
USB communication driver (libusb 1.0) 0.43
   0.000000 [D1] Built-in default or configured user for drivers 'nut' was ignored due to 'root' specified on command line
   0.000086 [D1] debug level is '1'
   0.001301 [D1] upsdrv_initups (non-SHUT)...
   0.006268 Using subdriver: Salicru HID 0.3
   0.006292 [D1] 2 HID objects found
   0.006487 nut_libusb_get_report: Success
   0.006509 [D1] Can't retrieve Report 00: Resource temporarily unavailable
   0.006534 [D1] Path: ff000001.ff000002, Type: Input, ReportID: 0x00, Offset: 0, Size: 8
   0.006739 nut_libusb_get_report: Success
   0.006744 [D1] Can't retrieve Report 00: Resource temporarily unavailable
   0.006750 [D1] Path: ff000001.ff000002, Type: Output, ReportID: 0x00, Offset: 0, Size: 8
   0.006764 [D1] Detected a UPS: Salicru/TWIN3000-R
   0.006850 [D1] upsdrv_initinfo...
   0.006857 [D1] upsdrv_updateinfo...
   0.757015 [D1] Got 0 HID objects...
   0.757048 [D1] Quick update...
   0.757231 [D1] Group and/or user account for this driver was customized ('root:nut') compared to built-in defaults. Fixing socket '/var/state/ups/usbhid-ups-upsslc' ownership/access.
   0.757248 [D1] Group access for this driver successfully fixed
   0.757262 [D1] upsdrv_updateinfo...
   1.507501 [D1] Got 0 HID objects...
   1.507532 [D1] Quick update...
   2.758609 [D1] upsdrv_updateinfo...
   3.508807 [D1] Got 0 HID objects...
   3.508837 [D1] Quick update...

As you can see, it stucks in a loop, getting 0 HID objects. If I increase the debug level...

   0.757823 [D1] upsdrv_updateinfo...
   1.508007 [D2] nut_libusb_get_interrupt: Connection timed out
   1.508018 [D1] Got 0 HID objects...
   1.508024 [D1] Quick update...
   2.759135 [D1] upsdrv_updateinfo...
   3.509375 [D2] nut_libusb_get_interrupt: Connection timed out
   3.509392 [D1] Got 0 HID objects...
   3.509396 [D1] Quick update...

upsrw and upscmd -l both return nothing...

jimklimov commented 2 years ago

Interesting... seems like some usb layer issue; try the option for "pollonly" (iirc the name) to avoid interrupts. Some systems seem to only work with one or the other method.

fsckawk commented 2 years ago

Ok, I've tried with pollonly = "enabled" and log does show different output. But it can't get data back either:

   0.001932 Connected to UPS [upsslc]: usbhid-ups-upsslc
   0.002428 Running as foreground process, not saving a PID file
   0.002443 [D2] mainloop: polling 3 filedescriptors
   0.002480 [D2] mainloop: polling 3 filedescriptors
   0.002504 [D3] UPS [upsslc]: dump is done
   0.002511 [D2] mainloop: polling 3 filedescriptors
   2.004628 [D2] mainloop: no data available
   2.004669 [D2] mainloop: polling 3 filedescriptors
   4.006768 [D2] mainloop: no data available
   4.006814 [D2] mainloop: polling 3 filedescriptors
   6.008920 [D2] mainloop: no data available
   6.009000 [D3] Pinging UPS [upsslc]
   6.009049 [D2] mainloop: polling 3 filedescriptors
   6.009134 [D3] Got PONG from UPS [upsslc]
   6.009160 [D2] mainloop: polling 3 filedescriptors
   8.011289 [D2] mainloop: no data available

sudo /usr/local/ups/bin/usbhid-ups -D -u root -x pollonly -a upsslc

   0.000000 [D1] Built-in default or configured user for drivers 'nut' was ignored due to 'root' specified on command line
   0.000096 [D1] debug level is '1'
   0.001306 [D1] upsdrv_initups (non-SHUT)...
   0.004966 [D1] nut_libusb_open get iProduct failed, retrying...
   0.005119 [D1] nut_libusb_open get iProduct failed, retrying...
   0.005281 [D1] nut_libusb_open get iProduct failed, retrying...
   0.005455 [D1] nut_libusb_open get iSerialNumber failed, retrying...
   0.005627 [D1] nut_libusb_open get iSerialNumber failed, retrying...
   0.005786 [D1] nut_libusb_open get iSerialNumber failed, retrying...
   0.006593 Using subdriver: Salicru HID 0.3.1
   0.006599 [D1] 2 HID objects found
   0.006769 nut_libusb_get_report: Success
   0.006776 [D1] Can't retrieve Report 00: Resource temporarily unavailable
   0.006785 [D1] Path: ff000001.ff000002, Type: Input, ReportID: 0x00, Offset: 0, Size: 8
   0.006952 nut_libusb_get_report: Success
   0.006957 [D1] Can't retrieve Report 00: Resource temporarily unavailable
   0.006964 [D1] Path: ff000001.ff000002, Type: Output, ReportID: 0x00, Offset: 0, Size: 8
   0.006975 [D1] Detected a UPS: Salicru/unknown
   0.007049 [D1] upsdrv_initinfo...
   0.007061 [D1] upsdrv_updateinfo...
   0.007065 [D1] Not using interrupt pipe...
   0.007071 [D1] Quick update...
   0.007529 [D1] Group and/or user account for this driver was customized ('root:nut') compared to built-in defaults. Fixing socket '/var/state/ups/usbhid-ups-upsslc' ownership/access.
   0.007545 [D1] Group access for this driver successfully fixed
   0.007553 [D1] upsdrv_updateinfo...
   0.007557 [D1] Not using interrupt pipe...
   0.007561 [D1] Quick update...
   2.009644 [D1] upsdrv_updateinfo...
   2.009680 [D1] Not using interrupt pipe...
   2.009692 [D1] Quick update...
   4.011751 [D1] upsdrv_updateinfo...
   4.011785 [D1] Not using interrupt pipe...
   4.011796 [D1] Quick update...
jimklimov commented 2 years ago

Hm, one more idea popped up: since you've had to add a productid to the sources, chances are that the value is not listed in your installed udev.rules (or newer hwdb) and so the system default handler grabs the device and keeps the NUT driver out.

inode64 commented 1 year ago

I have managed to make the Salicru ADV3000-RT UPS work, following the ideas of issue #1856, the only thing that does not appear is "Not supported", and neither is the battery autonomy, for the rest, everything seems correct.

Bus 001 Device 002: ID 2e66:0101 Salicru ADV3000-RT
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 
  bDeviceSubClass         0 
  bDeviceProtocol         0 
  bMaxPacketSize0         8
  idVendor           0x2e66 
  idProduct          0x0101 
  bcdDevice            0.02
  iManufacturer           3 Salicru
  iProduct                1 ADV3000-RT
  iSerial                 2 0000000000000000
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x003b
    bNumInterfaces          2
    bConfigurationValue     1
    iConfiguration          0 
    bmAttributes         0xe0
      Self Powered
      Remote Wakeup
    MaxPower              100mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         3 Human Interface Device
      bInterfaceSubClass      0 
      bInterfaceProtocol      0 
      iInterface              0 
        HID Device Descriptor:
          bLength                 9
          bDescriptorType        33
          bcdHID               1.10
          bCountryCode           25 Spanish
          bNumDescriptors         1
          bDescriptorType        34 Report
          wDescriptorLength      27
         Report Descriptors: 
           ** UNAVAILABLE **
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0008  1x 8 bytes
        bInterval               8
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        1
      bAlternateSetting       0
      bNumEndpoints           1
      bInterfaceClass         3 Human Interface Device
      bInterfaceSubClass      0 
      bInterfaceProtocol      0 
      iInterface              0 
        HID Device Descriptor:
          bLength                 9
          bDescriptorType        33
          bcdHID               1.10
          bCountryCode           25 Spanish
          bNumDescriptors         1
          bDescriptorType        34 Report
          wDescriptorLength     625
         Report Descriptors: 
           ** UNAVAILABLE **
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x82  EP 2 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0008  1x 8 bytes
        bInterval               8
[sai2]
        driver = nutdrv_qx
        port = auto
        productid=0101
        vendorid=2e66
        protocol=voltronic
        subdriver=cypress
# /lib64/nut/nutdrv_qx -DDDDDD -s 2e66:0101 -x vendorid=2e66 -x productid=0101 -x protocol=voltronic -x subdriver=cypress -x port=auto -u root -g root

   0.000000 [D3] main_arg: var='vendorid' val='2e66'
   0.000028 [D5] send_to_all: SETINFO driver.parameter.vendorid "2e66"
   0.000033 [D3] main_arg: var='productid' val='0101'
   0.000037 [D5] send_to_all: SETINFO driver.parameter.productid "0101"
   0.000039 [D3] main_arg: var='protocol' val='voltronic'
   0.000042 [D5] send_to_all: SETINFO driver.parameter.protocol "voltronic"
   0.000045 [D3] main_arg: var='subdriver' val='cypress'
   0.000048 [D5] send_to_all: SETINFO driver.parameter.subdriver "cypress"
   0.000050 [D3] main_arg: var='port' val='auto'
   0.000053 [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000056 [D1] Built-in default or configured user for drivers 'nut' was ignored due to 'root' specified on command line
   0.000058 [D1] Built-in default or configured group for drivers 'nut' was ignored due to 'root' specified on command line
   0.000061 [D1] Network UPS Tools version 2.8.0-Windows-676-g72d32537 (release/snapshot of 2.8.0.1) built with x86_64-pc-linux-gnu-gcc (Gentoo 12.2.1_p20230121-r1 p10) 12.2.1 20230121 and configured with flags: --prefix=/usr --build=x86_64-pc-linux-gnu --host=x86_64-pc-linux-gnu --mandir=/usr/share/man --infodir=/usr/share/info --datadir=/usr/share --sysconfdir=/etc --localstatedir=/var/lib --disable-dependency-tracking --disable-silent-rules --docdir=/usr/share/doc/nut-9999 --htmldir=/usr/share/doc/nut-9999/html --with-sysroot=/ --libdir=/usr/lib64 --sysconfdir=/etc/nut --datarootdir=/usr/share/nut --datadir=/usr/share/nut --disable-static --with-statepath=/var/lib/nut --with-drvpath=/lib64/nut --with-htmlpath=/usr/share/nut/html --with-user=nut --with-group=nut --with-logfacility=LOG_DAEMON --with-dev --with-serial --with-systemdsystemunitdir=/lib/systemd/system --without-powerman --with-cgi --with-ipmi --with-freeipmi --with-snmp --with-ssl --without-wrap --with-usb --without-neon --without-avahi --with-drivers=al175,apcsmart,apcsmart-old,apcupsd-ups,bcmxcp,bcmxcp_usb,belkin,belkinunv,bestfcom,bestfortress,bestuferrups,bestups,blazer_ser,blazer_usb,clone,clone-outlet,dummy-ups,etapro,everups,gamatronic,genericups,isbmex,ivtscd,liebert,liebert-esp2,masterguard,metasys,mge-shut,mge-utalk,microdowell,nutdrv_qx,oneac,optiups,powercom,powerpanel,rhino,richcomm_usb,riello_ser,riello_usb,safenet,snmp-ups,solis,tripplite,tripplite_usb,tripplitesu,upscode2,usbhid-ups,victronups --with-cgipath=/usr/share/nut/cgi
   0.000066 [D1] debug level is '6'
   0.000333 [D1] Succeeded to become_user(root): now UID=0 GID=0
   0.000343 [D5] send_to_all: SETINFO device.type "ups"
   0.000346 [D5] send_to_all: SETINFO driver.state "init.device"
   0.000349 [D1] upsdrv_initups...
   0.002868 [D2] Checking device 1 of 8 (413C/A101)
   0.003507 [D2] - VendorID: 413c
   0.003511 [D2] - ProductID: a101
   0.003514 [D2] - Manufacturer: DELL
   0.003516 [D2] - Product: Internal Dual SD
   0.003518 [D2] - Serial Number: 012345678901
   0.003521 [D2] - Bus: 002
   0.003523 [D2] - Device: 002
   0.003526 [D2] - Device release number: 0000
   0.003528 [D2] Trying to match device
   0.003531 [D3] match_function_regex: matching a device...
   0.003536 [D2] match_function_regex: failed match of VendorID: 413c
   0.003538 [D2] Device does not match - skipping
   0.003544 [D2] Checking device 2 of 8 (1D6B/0003)
   0.003581 [D2] - VendorID: 1d6b
   0.003584 [D2] - ProductID: 0003
   0.003586 [D2] - Manufacturer: Linux 6.1.19-gentoo xhci-hcd
   0.003589 [D2] - Product: xHCI Host Controller
   0.003591 [D2] - Serial Number: 0000:00:14.0
   0.003593 [D2] - Bus: 002
   0.003595 [D2] - Device: 001
   0.003598 [D2] - Device release number: 0601
   0.003600 [D2] Trying to match device
   0.003602 [D3] match_function_regex: matching a device...
   0.003605 [D2] match_function_regex: failed match of VendorID: 1d6b
   0.003607 [D2] Device does not match - skipping
   0.003612 [D2] Checking device 3 of 8 (1604/10C0)
   0.003619 [D2] - VendorID: 1604
   0.003621 [D2] - ProductID: 10c0
   0.003624 [D2] - Manufacturer: unknown
   0.003626 [D2] - Product: unknown
   0.003628 [D2] - Serial Number: unknown
   0.003630 [D2] - Bus: 001
   0.003640 [D2] - Device: 006
   0.003642 [D2] - Device release number: 0000
   0.003644 [D2] Trying to match device
   0.003646 [D3] match_function_regex: matching a device...
   0.003649 [D2] match_function_regex: failed match of VendorID: 1604
   0.003651 [D2] Device does not match - skipping
   0.003655 [D2] Checking device 4 of 8 (1604/10C0)
   0.003660 [D2] - VendorID: 1604
   0.003662 [D2] - ProductID: 10c0
   0.003665 [D2] - Manufacturer: unknown
   0.003667 [D2] - Product: unknown
   0.003669 [D2] - Serial Number: unknown
   0.003671 [D2] - Bus: 001
   0.003673 [D2] - Device: 005
   0.003676 [D2] - Device release number: 0000
   0.003678 [D2] Trying to match device
   0.003680 [D3] match_function_regex: matching a device...
   0.003683 [D2] match_function_regex: failed match of VendorID: 1604
   0.003685 [D2] Device does not match - skipping
   0.003688 [D2] Checking device 5 of 8 (1604/10C0)
   0.003693 [D2] - VendorID: 1604
   0.003695 [D2] - ProductID: 10c0
   0.003698 [D2] - Manufacturer: unknown
   0.003700 [D2] - Product: unknown
   0.003702 [D2] - Serial Number: unknown
   0.003704 [D2] - Bus: 001
   0.003706 [D2] - Device: 004
   0.003709 [D2] - Device release number: 0000
   0.003711 [D2] Trying to match device
   0.003713 [D3] match_function_regex: matching a device...
   0.003715 [D2] match_function_regex: failed match of VendorID: 1604
   0.003718 [D2] Device does not match - skipping
   0.003721 [D2] Checking device 6 of 8 (10D5/5A08)
   0.005888 [D2] - VendorID: 10d5
   0.005891 [D2] - ProductID: 5a08
   0.005893 [D2] - Manufacturer: No brand
   0.005895 [D2] - Product: KVM
   0.005898 [D2] - Serial Number: unknown
   0.005900 [D2] - Bus: 001
   0.005902 [D2] - Device: 003
   0.005904 [D2] - Device release number: 0000
   0.005906 [D2] Trying to match device
   0.005909 [D3] match_function_regex: matching a device...
   0.005911 [D2] match_function_regex: failed match of VendorID: 10d5
   0.005913 [D2] Device does not match - skipping
   0.005917 [D2] Checking device 7 of 8 (2E66/0101)
   0.007574 [D2] - VendorID: 2e66
   0.007577 [D2] - ProductID: 0101
   0.007579 [D2] - Manufacturer: Salicru
   0.007582 [D2] - Product: ADV3000-RT
   0.007584 [D2] - Serial Number: 0000000000000000
   0.007586 [D2] - Bus: 001
   0.007588 [D2] - Device: 002
   0.007590 [D2] - Device release number: 0002
   0.007593 [D2] Trying to match device
   0.007595 [D3] match_function_regex: matching a device...
   0.007635 [D2] Device matches
   0.007638 [D2] Reading first configuration descriptor
   0.007644 [D3] libusb_kernel_driver_active() returned 0: Success
   0.007655 [D2] Claimed interface 0 successfully
   0.007658 [D3] nut_usb_set_altinterface: skipped libusb_set_interface_alt_setting(udev, 0, 0)
   0.007664 [D5] send_to_all: SETINFO ups.vendorid "2e66"
   0.007668 [D5] send_to_all: SETINFO ups.productid "0101"
   0.007902 [D3] send: QGS
   0.015685 [D5] read [  0]: (8 bytes) => 28 56 45 52 46 57 3a 30
   0.079672 [D5] read [  8]: (8 bytes) => 28 32 33 30 2e 33 20 35
   0.087667 [D5] read [ 16]: (8 bytes) => 30 2e 30 20 32 33 30 2e
   0.103670 [D5] read [ 24]: (8 bytes) => 33 20 35 30 2e 30 20 30
   0.119676 [D5] read [ 32]: (8 bytes) => 30 31 2e 38 20 30 31 34
   0.135671 [D5] read [ 40]: (8 bytes) => 20 33 36 36 2e 31 20 2d
   0.151674 [D5] read [ 48]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   0.159668 [D5] read [ 56]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   0.191680 [D5] read [ 64]: (8 bytes) => 20 30 32 30 2e 35 20 30
   0.207668 [D5] read [ 72]: (8 bytes) => 31 30 30 30 30 30 30 30
   0.215671 [D5] read [ 80]: (8 bytes) => 30 30 31 0d 00 00 00 00
   0.215679 [D3] read: (VERFW:0(230.3 50.0 230.3 50.0 001.8 014 366.1 ---.- 082.1 ---.- 020.5 010000000001
   0.215690 [D2] ups_infoval_set: non numerical value [input.voltage: VERFW]
   0.215926 [D3] send: QGS
   0.447711 [D5] read [  0]: (8 bytes) => 28 32 33 31 2e 30 20 34
   0.455678 [D5] read [  8]: (8 bytes) => 39 2e 39 20 32 33 31 2e
   0.471673 [D5] read [ 16]: (8 bytes) => 30 20 35 30 2e 30 20 30
   0.487669 [D5] read [ 24]: (8 bytes) => 30 31 2e 37 20 30 31 34
   0.503672 [D5] read [ 32]: (8 bytes) => 20 33 36 36 2e 30 20 2d
   0.519670 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   0.527668 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   0.543669 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
   0.551668 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   0.559675 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   0.559685 [D3] read: (231.0 49.9 231.0 50.0 001.7 014 366.0 ---.- 082.1 ---.- 020.5 010000000001
   0.559702 [D5] send_to_all: SETINFO input.voltage "231.0"
   0.559936 [D3] send: QPI
   0.807700 [D5] read [  0]: (8 bytes) => 28 50 49 30 30 0d 00 00
   0.807714 [D3] read: (PI00
   0.807741 [D5] send_to_all: SETINFO ups.firmware.aux "P00"
   0.807744 Using protocol: Voltronic 0.06
   0.807748 [D5] send_to_all: SETINFO driver.state "init.quiet"
   0.807753 [D5] send_to_all: SETINFO driver.version "2.8.0-Windows-676-g72d32537"
   0.807757 [D5] send_to_all: SETINFO driver.version.internal "0.32"
   0.807760 [D5] send_to_all: SETINFO driver.name "nutdrv_qx"
   0.807763 [D5] send_to_all: SETINFO driver.state "init.info"
   0.807766 [D1] upsdrv_initinfo...
   0.807770 [D5] send_to_all: SETINFO driver.version.data "Voltronic 0.06"
   0.808008 [D3] send: QRI
   0.871694 [D5] read [  0]: (8 bytes) => 28 32 33 30 2e 30 20 30
   0.887677 [D5] read [  8]: (8 bytes) => 31 33 20 30 37 32 2e 30
   0.895669 [D5] read [ 16]: (8 bytes) => 20 35 30 2e 30 0d 00 00
   0.895674 [D3] read: (230.0 013 072.0 50.0
   0.895688 [D5] send_to_all: SETINFO output.voltage.nominal "230.0"
   0.895695 [D5] send_to_all: SETINFO output.current.nominal "13"
   0.895700 [D5] send_to_all: SETINFO battery.voltage.nominal "72.0"
   0.895705 [D5] send_to_all: SETINFO output.frequency.nominal "50.0"
   0.895936 [D3] send: QMD
   0.951672 [D5] read [  0]: (8 bytes) => 28 23 23 23 23 41 44 56
   0.975681 [D5] read [  8]: (8 bytes) => 33 30 30 30 2d 52 54 32
   0.991669 [D5] read [ 16]: (8 bytes) => 20 23 23 23 33 30 30 30
   1.007688 [D5] read [ 24]: (8 bytes) => 20 39 30 20 31 2f 31 20
   1.023687 [D5] read [ 32]: (8 bytes) => 32 33 30 20 32 33 30 20
   1.047676 [D5] read [ 40]: (8 bytes) => 30 36 20 31 32 2e 30 0d
   1.047682 [D3] read: (####ADV3000-RT2 ###3000 90 1/1 230 230 06 12.0
   1.047697 [D5] send_to_all: SETINFO device.model "ADV3000-RT2"
   1.047703 [D5] send_to_all: SETINFO ups.power.nominal "3000"
   1.047711 [D5] send_to_all: SETINFO output.powerfactor "0.9"
   1.047715 [D5] send_to_all: SETINFO input.phases "1"
   1.047719 [D5] send_to_all: SETINFO output.phases "1"
   1.047725 [D5] send_to_all: SETINFO input.voltage.nominal "230.0"
   1.049532 [D3] send: F
   1.255705 [D5] read [  0]: (8 bytes) => 23 32 33 30 2e 30 20 30
   1.271677 [D5] read [  8]: (8 bytes) => 31 33 20 30 37 32 2e 30
   1.279673 [D5] read [ 16]: (8 bytes) => 20 35 30 2e 30 0d 00 00
   1.279679 [D3] read: #230.0 013 072.0 50.0
   1.279692 [D5] send_to_all: SETINFO input.current.nominal "13.0"
   1.279702 [D5] send_to_all: SETINFO input.frequency.nominal "50.0"
   1.279937 [D3] send: QMF
   1.423699 [D5] read [  0]: (8 bytes) => 28 23 23 23 23 23 23 23
   1.431675 [D5] read [  8]: (8 bytes) => 23 23 23 23 23 23 23 23
   1.439673 [D5] read [ 16]: (8 bytes) => 23 23 23 23 23 23 23 23
   1.447672 [D5] read [ 24]: (8 bytes) => 23 23 23 0d 00 00 00 00
   1.447677 [D3] read: (##########################
   1.447685 [D1] ups_infoval_set: non significant value [device.mfr]
   1.447925 [D3] send: QVFW
   1.583701 [D5] read [  0]: (8 bytes) => 28 56 45 52 46 57 3a 30
   1.591675 [D5] read [  8]: (8 bytes) => 32 31 32 34 2e 30 32 0d
   1.591680 [D3] read: (VERFW:02124.02
   1.591689 [D5] send_to_all: SETINFO ups.firmware "02124.02"
   1.591925 [D3] send: QID
   1.655691 [D5] read [  0]: (8 bytes) => 28 30 30 30 30 30 30 30
   1.663677 [D5] read [  8]: (8 bytes) => 30 30 30 30 30 30 30 30
   1.671676 [D5] read [ 16]: (8 bytes) => 30 0d 00 00 00 00 00 00
   1.671681 [D3] read: (0000000000000000
   1.671687 device.serial: UPS reported a non-significant serial [0000000000000000]
   1.671690 [D4] ups_infoval_set: failed to preprocess value [device.serial: 0000000000000000]
   1.671942 [D3] send: I
   1.703689 [D5] read [  0]: (8 bytes) => 23 20 20 20 20 20 20 20
   1.711675 [D5] read [  8]: (8 bytes) => 20 20 20 20 20 20 20 20
   1.735681 [D5] read [ 16]: (8 bytes) => 20 41 44 56 33 30 30 30
   1.751678 [D5] read [ 24]: (8 bytes) => 20 20 20 20 30 32 31 32
   1.759674 [D5] read [ 32]: (8 bytes) => 34 2e 30 32 20 20 0d 00
   1.759679 [D3] read: #                ADV3000    02124.02  
   1.759686 [D1] ups_infoval_set: non significant value [device.mfr]
   1.761790 [D3] send: QGS
   2.031705 [D5] read [  0]: (8 bytes) => 28 32 33 31 2e 30 20 35
   2.039680 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 33 30 2e
   2.071708 [D5] read [ 16]: (8 bytes) => 39 20 35 30 2e 30 20 30
   2.079689 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 34
   2.095705 [D5] read [ 32]: (8 bytes) => 20 33 36 35 2e 34 20 2d
   2.111699 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   2.119695 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   2.135692 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
   2.151680 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   2.159677 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   2.159683 [D3] read: (231.0 50.0 230.9 50.0 001.8 014 365.4 ---.- 082.1 ---.- 020.5 010000000001
   2.159698 [D5] send_to_all: SETINFO input.frequency "50.0"
   2.159707 [D5] send_to_all: SETINFO output.voltage "230.9"
   2.159712 [D5] send_to_all: SETINFO output.frequency "50.0"
   2.159716 [D5] send_to_all: SETINFO output.current "1.8"
   2.159721 [D5] send_to_all: SETINFO ups.load "14"
   2.159726 [D5] send_to_all: SETINFO battery.voltage "82.10"
   2.159730 [D5] send_to_all: SETINFO ups.temperature "20.5"
   2.159735 [D5] send_to_all: SETINFO ups.type "line-interactive"
   2.159739 [D5] update_status: OL
   2.159742 [D5] update_status: !LB
   2.159747 [D5] update_status: !CAL
   2.159750 [D5] update_status: !FSD
   2.159754 [D5] send_to_all: SETINFO ups.beeper.status "enabled"
   2.159988 [D3] send: QMOD
   2.303708 [D5] read [  0]: (8 bytes) => 28 4c 0d 00 00 00 00 00
   2.303729 [D3] read: (L
   2.303737 [D5] update_status: OL
   2.303977 [D3] send: QWS
   2.359701 [D5] read [  0]: (8 bytes) => 28 30 30 30 30 30 30 30
   2.367677 [D5] read [  8]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.383680 [D5] read [ 16]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.391678 [D5] read [ 24]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.407678 [D5] read [ 32]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.415680 [D5] read [ 40]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.439680 [D5] read [ 48]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.447678 [D5] read [ 56]: (8 bytes) => 30 30 30 30 30 30 30 30
   2.455677 [D5] read [ 64]: (8 bytes) => 30 0d 00 00 00 00 00 00
   2.455683 [D3] read: (0000000000000000000000000000000000000000000000000000000000000000
   2.455927 [D3] send: QBV
   2.511691 [D5] read [  0]: (8 bytes) => 28 30 38 32 2e 31 20 30
   2.535698 [D5] read [  8]: (8 bytes) => 36 20 30 31 20 31 30 30
   2.543678 [D5] read [ 16]: (8 bytes) => 20 30 30 30 35 37 0d 00
   2.543683 [D3] read: (082.1 06 01 100 00057
   2.543691 battery_number: 6
   2.543704 battery_number, settable range: 1..9
   2.543714 [D5] send_to_all: SETINFO battery.packs "1"
   2.543717 [D5] send_to_all: SETFLAGS battery.packs RW
   2.543721 [D5] send_to_all: ADDRANGE battery.packs 1 99
   2.543724 [D5] send_to_all: SETFLAGS battery.packs RW NUMBER
   2.543729 [D5] send_to_all: SETINFO battery.charge "100"
   2.543735 [D5] send_to_all: SETINFO battery.runtime "0"
   2.543969 [D3] send: QLDL
   2.607700 [D5] read [  0]: (8 bytes) => 28 30 31 31 20 30 31 33
   2.615688 [D5] read [  8]: (8 bytes) => 0d 00 00 00 00 00 00 00
   2.615697 [D3] read: (011 013
   2.615707 [D5] send_to_all: SETINFO output.power.minimum.percent "11"
   2.615713 [D5] send_to_all: SETINFO output.power.maximum.percent "13"
   2.615949 [D3] send: QFLAG
   2.751698 [D5] read [  0]: (8 bytes) => 28 45 62 72 61 73 63 6c
   2.815702 [D5] read [  8]: (8 bytes) => 7a 30 44 67 6a 6d 0d 00
   2.815730 [D3] read: (Ebrasclz0Dgjm
   2.815748 [D5] send_to_all: SETINFO ups.start.auto "yes"
   2.815752 [D5] send_to_all: SETFLAGS ups.start.auto RW
   2.815756 [D5] send_to_all: ADDENUM ups.start.auto "no"
   2.815759 [D5] send_to_all: ADDENUM ups.start.auto "yes"
   2.815765 [D5] send_to_all: SETINFO battery.protection "yes"
   2.815768 [D5] send_to_all: SETFLAGS battery.protection RW
   2.815771 [D5] send_to_all: ADDENUM battery.protection "no"
   2.815774 [D5] send_to_all: ADDENUM battery.protection "yes"
   2.815778 [D5] send_to_all: SETINFO battery.energysave "no"
   2.815781 [D5] send_to_all: SETFLAGS battery.energysave RW
   2.815783 [D5] send_to_all: ADDENUM battery.energysave "no"
   2.815786 [D5] send_to_all: ADDENUM battery.energysave "yes"
   2.815791 [D5] send_to_all: SETINFO ups.start.battery "yes"
   2.815794 [D5] send_to_all: SETFLAGS ups.start.battery RW
   2.815797 [D5] send_to_all: ADDENUM ups.start.battery "no"
   2.815799 [D5] send_to_all: ADDENUM ups.start.battery "yes"
   2.815805 [D5] send_to_all: SETINFO outlet.0.switchable "no"
   2.815807 [D5] send_to_all: SETFLAGS outlet.0.switchable RW
   2.815810 [D5] send_to_all: ADDENUM outlet.0.switchable "no"
   2.815813 [D5] send_to_all: ADDENUM outlet.0.switchable "yes"
   2.815817 [D4] ups_infoval_set: failed to preprocess value [bypass_alarm: Ebrasclz0Dgjm]
   2.815821 battery_alarm: enabled
   2.815824 [D4] ups_infoval_set: failed to preprocess value [bypass_when_off: Ebrasclz0Dgjm]
   2.815832 alarm_control: enabled
   2.815835 [D4] ups_infoval_set: failed to preprocess value [converter_mode: Ebrasclz0Dgjm]
   2.815838 [D4] ups_infoval_set: failed to preprocess value [eco_mode: Ebrasclz0Dgjm]
   2.815841 [D4] ups_infoval_set: failed to preprocess value [battery_open_status_check: Ebrasclz0Dgjm]
   2.815844 [D4] ups_infoval_set: failed to preprocess value [bypass_forbidding: Ebrasclz0Dgjm]
   2.815848 site_fault_detection: enabled
   2.815851 [D4] ups_infoval_set: failed to preprocess value [advanced_eco_mode: Ebrasclz0Dgjm]
   2.815854 [D4] ups_infoval_set: failed to preprocess value [constant_phase_angle: Ebrasclz0Dgjm]
   2.815857 [D4] ups_infoval_set: failed to preprocess value [limited_runtime_on_battery: Ebrasclz0Dgjm]
   2.815862 [D2] setvar: nothing to do... [battery_number]
   2.816099 [D3] send: RE0
   2.887701 [D5] read [  0]: (8 bytes) => 28 4e 41 4b 0d 00 00 00
   2.887708 [D3] read: (NAK
   2.887711 [D2] qx_process_answer: query rejected by the UPS (battery.voltage.low)
   2.887946 [D3] send: QPD
   2.951692 [D5] read [  0]: (8 bytes) => 28 4e 41 4b 0d 00 00 00
   2.951703 [D3] read: (NAK
   2.951707 [D2] qx_process_answer: query rejected by the UPS (input_phase_angle)
   2.951713 [D2] qx_process_answer: query rejected by the UPS (output_phase_angle)
   2.953888 [D3] send: QPAR
   3.111705 [D5] read [  0]: (8 bytes) => 28 4e 41 4b 0d 00 00 00
   3.111720 [D3] read: (NAK
   3.111725 [D2] qx_process_answer: query rejected by the UPS (voltronic_parallel)
   3.111732 [D5] send_to_all: ADDCMD load.off
   3.111735 [D5] send_to_all: ADDCMD load.on
   3.111738 [D5] send_to_all: ADDCMD shutdown.return
   3.111741 [D5] send_to_all: ADDCMD shutdown.stayoff
   3.111744 [D5] send_to_all: ADDCMD shutdown.stop
   3.111747 [D5] send_to_all: ADDCMD test.battery.start
   3.111750 [D5] send_to_all: ADDCMD test.battery.start.deep
   3.111752 [D5] send_to_all: ADDCMD test.battery.start.quick
   3.111755 [D5] send_to_all: ADDCMD test.battery.stop
   3.111759 [D5] send_to_all: ADDCMD beeper.toggle
   3.111761 [D5] send_to_all: ADDCMD beeper.enable
   3.111764 [D5] send_to_all: ADDCMD beeper.disable
   3.111769 [D5] send_to_all: SETINFO ups.delay.start "180"
   3.111772 [D5] send_to_all: SETFLAGS ups.delay.start RW
   3.111776 [D5] send_to_all: ADDRANGE ups.delay.start 0 599940
   3.111779 [D5] send_to_all: SETFLAGS ups.delay.start RW NUMBER
   3.111782 [D5] send_to_all: SETINFO ups.delay.shutdown "30"
   3.111785 [D5] send_to_all: SETFLAGS ups.delay.shutdown RW
   3.111788 [D5] send_to_all: ADDRANGE ups.delay.shutdown 12 5940
   3.111790 [D5] send_to_all: SETFLAGS ups.delay.shutdown RW NUMBER
   3.111811 [D5] send_to_all: SETINFO driver.parameter.pollfreq "30"
   3.111821 [D5] send_to_all: SETINFO driver.state "init.updateinfo"
   3.111824 [D1] upsdrv_updateinfo...
   3.111827 [D1] Quick update...
   3.112064 [D3] send: QGS
   3.191695 [D5] read [  0]: (8 bytes) => 28 32 33 30 2e 38 20 35
   3.207689 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 33 30 2e
   3.223688 [D5] read [ 16]: (8 bytes) => 39 20 35 30 2e 30 20 30
   3.239687 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 34
   3.255684 [D5] read [ 32]: (8 bytes) => 20 33 36 34 2e 39 20 2d
   3.271682 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   3.279681 [D5] read [ 48]: (8 bytes) => 2e 30 20 2d 2d 2d 2e 2d
   3.295681 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 33 20 30
   3.303680 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   3.311691 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   3.311702 [D3] read: (230.8 50.0 230.9 50.0 001.8 014 364.9 ---.- 082.0 ---.- 020.3 010000000001
   3.311710 [D5] update_status: OL
   3.311714 [D5] update_status: !LB
   3.311718 [D5] update_status: !CAL
   3.311721 [D5] update_status: !FSD
   3.311730 [D5] send_to_all: SETINFO ups.status "OL"
   3.311733 [D5] send_to_all: DATAOK
   3.311737 [D5] send_to_all: SETINFO driver.state "init.quiet"
   3.311818 [D2] dstate_init: sock /var/lib/nut/nutdrv_qx-2e66:0101 open on fd 10
   3.311853 [D1] Group and/or user account for this driver was customized ('root:root') compared to built-in defaults. Fixing socket '/var/lib/nut/nutdrv_qx-2e66:0101' ownership/access.
   3.311864 [D1] Group access for this driver successfully fixed
   3.311869 [D5] send_to_all: SETINFO driver.parameter.pollinterval "2"
   3.311872 [D5] send_to_all: SETINFO driver.parameter.synchronous "auto"
   3.311876 [D5] send_to_all: SETINFO driver.state "quiet"
   3.311879 [D1] Driver initialization completed, beginning regular infinite loop
   3.311883 [D6] upsnotify: notify about state 2 with libsystemd: was requested, but not running as a service unit now, will not spam more about it
   3.311886 [D6] upsnotify: failed to notify about state 2: no notification tech defined, will not spam more about it
   3.311889 [D6] upsnotify: logged the systemd watchdog situation once, will not spam more about it
   3.311892 [D5] send_to_all: SETINFO driver.state "updateinfo"
   3.311895 [D1] upsdrv_updateinfo...
   3.311898 [D1] Quick update...
   3.312133 [D3] send: QGS
   3.455708 [D5] read [  0]: (8 bytes) => 28 32 33 30 2e 34 20 35
   3.471697 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 33 30 2e
   3.487688 [D5] read [ 16]: (8 bytes) => 36 20 35 30 2e 30 20 30
   3.495685 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 34
   3.511685 [D5] read [ 32]: (8 bytes) => 20 33 36 34 2e 38 20 2d
   3.527701 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   3.535686 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   3.559691 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
   3.567685 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   3.575688 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   3.575697 [D3] read: (230.4 50.0 230.6 50.0 001.8 014 364.8 ---.- 082.1 ---.- 020.5 010000000001
   3.575705 [D5] update_status: OL
   3.575710 [D5] update_status: !LB
   3.575714 [D5] update_status: !CAL
   3.575717 [D5] update_status: !FSD
   3.575726 [D5] send_to_all: SETINFO driver.state "quiet"
   5.312551 [D5] send_to_all: SETINFO driver.state "updateinfo"
   5.312576 [D1] upsdrv_updateinfo...
   5.312580 [D1] Quick update...
   5.312840 [D3] send: QGS
   5.367715 [D5] read [  0]: (8 bytes) => 28 32 33 30 2e 31 20 35
   5.383708 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 39 2e
   5.399694 [D5] read [ 16]: (8 bytes) => 38 20 35 30 2e 30 20 30
   5.415693 [D5] read [ 24]: (8 bytes) => 30 31 2e 37 20 30 31 34
   5.431703 [D5] read [ 32]: (8 bytes) => 20 33 36 36 2e 32 20 2d
   5.439707 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   5.455698 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   5.495706 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
   5.503689 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   5.511689 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   5.511693 [D3] read: (230.1 50.0 229.8 50.0 001.7 014 366.2 ---.- 082.1 ---.- 020.5 010000000001
   5.511701 [D5] update_status: OL
   5.511705 [D5] update_status: !LB
   5.511709 [D5] update_status: !CAL
   5.511712 [D5] update_status: !FSD
   5.511720 [D5] send_to_all: SETINFO driver.state "quiet"
   7.314364 [D5] send_to_all: SETINFO driver.state "updateinfo"
   7.314394 [D1] upsdrv_updateinfo...
   7.314399 [D1] Quick update...
   7.314660 [D3] send: QGS
   7.367718 [D5] read [  0]: (8 bytes) => 28 32 33 30 2e 37 20 35
   7.375698 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 33 30 2e
   7.391701 [D5] read [ 16]: (8 bytes) => 38 20 35 30 2e 30 20 30
   7.407699 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 33
   7.423698 [D5] read [ 32]: (8 bytes) => 20 33 36 35 2e 33 20 2d
   7.431698 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   7.447699 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   7.463696 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
   7.471697 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   7.479700 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   7.479709 [D3] read: (230.7 50.0 230.8 50.0 001.8 013 365.3 ---.- 082.1 ---.- 020.5 010000000001
   7.479717 [D5] update_status: OL
   7.479722 [D5] update_status: !LB
   7.479726 [D5] update_status: !CAL
   7.479729 [D5] update_status: !FSD
   7.479739 [D5] send_to_all: SETINFO driver.state "quiet"
   9.316214 [D5] send_to_all: SETINFO driver.state "updateinfo"
   9.316238 [D1] upsdrv_updateinfo...
   9.316242 [D1] Quick update...
   9.316501 [D3] send: QGS
   9.367723 [D5] read [  0]: (8 bytes) => 28 32 33 30 2e 34 20 35
   9.375706 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 33 30 2e
   9.399707 [D5] read [ 16]: (8 bytes) => 38 20 35 30 2e 30 20 30
   9.407727 [D5] read [ 24]: (8 bytes) => 30 31 2e 37 20 30 31 33
   9.431710 [D5] read [ 32]: (8 bytes) => 20 33 36 34 2e 36 20 2d
   9.439712 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
   9.455709 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
   9.487711 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 33 20 30
   9.495707 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
   9.503707 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
   9.503713 [D3] read: (230.4 50.0 230.8 50.0 001.7 013 364.6 ---.- 082.1 ---.- 020.3 010000000001
   9.503721 [D5] update_status: OL
   9.503726 [D5] update_status: !LB
   9.503731 [D5] update_status: !CAL
   9.503735 [D5] update_status: !FSD
   9.503743 [D5] send_to_all: SETINFO driver.state "quiet"
  11.318043 [D5] send_to_all: SETINFO driver.state "updateinfo"
  11.318073 [D1] upsdrv_updateinfo...
  11.318080 [D1] Quick update...
  11.318346 [D3] send: QGS
  11.375744 [D5] read [  0]: (8 bytes) => 28 32 32 39 2e 31 20 35
  11.383731 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 39 2e
  11.399731 [D5] read [ 16]: (8 bytes) => 30 20 35 30 2e 30 20 30
  11.415730 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 33
  11.431726 [D5] read [ 32]: (8 bytes) => 20 33 36 36 2e 32 20 2d
  11.439720 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
  11.455725 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
  11.471725 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 33 20 30
  11.479731 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
  11.487722 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
  11.487729 [D3] read: (229.1 50.0 229.0 50.0 001.8 013 366.2 ---.- 082.1 ---.- 020.3 010000000001
  11.487737 [D5] update_status: OL
  11.487742 [D5] update_status: !LB
  11.487748 [D5] update_status: !CAL
  11.487751 [D5] update_status: !FSD
  11.487760 [D5] send_to_all: SETINFO driver.state "quiet"
  13.319884 [D5] send_to_all: SETINFO driver.state "updateinfo"
  13.319910 [D1] upsdrv_updateinfo...
  13.319915 [D1] Quick update...
  13.320177 [D3] send: QGS
  13.383745 [D5] read [  0]: (8 bytes) => 28 32 32 38 2e 38 20 35
  13.399728 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 38 2e
  13.431741 [D5] read [ 16]: (8 bytes) => 38 20 35 30 2e 30 20 30
  13.447733 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 33
  13.463724 [D5] read [ 32]: (8 bytes) => 20 33 36 35 2e 34 20 2d
  13.471724 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
  13.487730 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
  13.503729 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 33 20 30
  13.511724 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
  13.519743 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
  13.519750 [D3] read: (228.8 50.0 228.8 50.0 001.8 013 365.4 ---.- 082.1 ---.- 020.3 010000000001
  13.519758 [D5] update_status: OL
  13.519763 [D5] update_status: !LB
  13.519767 [D5] update_status: !CAL
  13.519770 [D5] update_status: !FSD
  13.519779 [D5] send_to_all: SETINFO driver.state "quiet"
  15.321696 [D5] send_to_all: SETINFO driver.state "updateinfo"
  15.321715 [D1] upsdrv_updateinfo...
  15.321720 [D1] Quick update...
  15.321976 [D3] send: QGS
  15.367761 [D5] read [  0]: (8 bytes) => 28 32 32 38 2e 38 20 35
  15.383739 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 38 2e
  15.399741 [D5] read [ 16]: (8 bytes) => 37 20 35 30 2e 30 20 30
  15.407735 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 33
  15.431747 [D5] read [ 32]: (8 bytes) => 20 33 36 34 2e 36 20 2d
  15.439737 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
  15.455745 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
  15.471740 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
  15.479738 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
  15.487746 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
  15.487760 [D3] read: (228.8 50.0 228.7 50.0 001.8 013 364.6 ---.- 082.1 ---.- 020.5 010000000001
  15.487768 [D5] update_status: OL
  15.487773 [D5] update_status: !LB
  15.487778 [D5] update_status: !CAL
  15.487782 [D5] update_status: !FSD
  15.487791 [D5] send_to_all: SETINFO driver.state "quiet"
  17.322549 [D5] send_to_all: SETINFO driver.state "updateinfo"
  17.322566 [D1] upsdrv_updateinfo...
  17.322572 [D1] Quick update...
  17.322843 [D3] send: QGS
  17.367751 [D5] read [  0]: (8 bytes) => 28 32 32 37 2e 36 20 35
  17.383743 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 38 2e
  17.399741 [D5] read [ 16]: (8 bytes) => 30 20 35 30 2e 30 20 30
  17.415742 [D5] read [ 24]: (8 bytes) => 30 31 2e 37 20 30 31 33
  17.431754 [D5] read [ 32]: (8 bytes) => 20 33 36 35 2e 38 20 2d
  17.439742 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
  17.455745 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
  17.471741 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
  17.479741 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
  17.487748 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
  17.487755 [D3] read: (227.6 50.0 228.0 50.0 001.7 013 365.8 ---.- 082.1 ---.- 020.5 010000000001
  17.487763 [D5] update_status: OL
  17.487768 [D5] update_status: !LB
  17.487774 [D5] update_status: !CAL
  17.487777 [D5] update_status: !FSD
  17.487787 [D5] send_to_all: SETINFO driver.state "quiet"
  19.324397 [D5] send_to_all: SETINFO driver.state "updateinfo"
  19.324420 [D1] upsdrv_updateinfo...
  19.324425 [D1] Quick update...
  19.324680 [D3] send: QGS
  19.391757 [D5] read [  0]: (8 bytes) => 28 32 32 39 2e 32 20 35
  19.415750 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 39 2e
  19.431755 [D5] read [ 16]: (8 bytes) => 30 20 35 30 2e 30 20 30
  19.447752 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 33
  19.463749 [D5] read [ 32]: (8 bytes) => 20 33 36 35 2e 31 20 2d
  19.479751 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
  19.487749 [D5] read [ 48]: (8 bytes) => 2e 31 20 2d 2d 2d 2e 2d
  19.511764 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
  19.519750 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
  19.527752 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
  19.527759 [D3] read: (229.2 50.0 229.0 50.0 001.8 013 365.1 ---.- 082.1 ---.- 020.5 010000000001
  19.527768 [D5] update_status: OL
  19.527784 [D5] update_status: !LB
  19.527788 [D5] update_status: !CAL
  19.527792 [D5] update_status: !FSD
  19.527801 [D5] send_to_all: SETINFO driver.state "quiet"
  21.326211 [D5] send_to_all: SETINFO driver.state "updateinfo"
  21.326234 [D1] upsdrv_updateinfo...
  21.326240 [D1] Quick update...
  21.326498 [D3] send: QGS
  21.391790 [D5] read [  0]: (8 bytes) => 28 32 32 39 2e 31 20 35
  21.399764 [D5] read [  8]: (8 bytes) => 30 2e 30 20 32 32 39 2e
  21.423778 [D5] read [ 16]: (8 bytes) => 31 20 35 30 2e 30 20 30
  21.431767 [D5] read [ 24]: (8 bytes) => 30 31 2e 38 20 30 31 34
  21.447770 [D5] read [ 32]: (8 bytes) => 20 33 36 35 2e 35 20 2d
  21.463770 [D5] read [ 40]: (8 bytes) => 2d 2d 2e 2d 20 30 38 32
  21.471781 [D5] read [ 48]: (8 bytes) => 2e 30 20 2d 2d 2d 2e 2d
  21.487768 [D5] read [ 56]: (8 bytes) => 20 30 32 30 2e 35 20 30
  21.495767 [D5] read [ 64]: (8 bytes) => 31 30 30 30 30 30 30 30
  21.503764 [D5] read [ 72]: (8 bytes) => 30 30 31 0d 00 00 00 00
  21.503771 [D3] read: (229.1 50.0 229.1 50.0 001.8 014 365.5 ---.- 082.0 ---.- 020.5 010000000001
  21.503779 [D5] update_status: OL
  21.503782 [D5] update_status: !LB
  21.503786 [D5] update_status: !CAL
  21.503790 [D5] update_status: !FSD
  21.503799 [D5] send_to_all: SETINFO driver.state "quiet"