nutdrv_qx not detecting GREEN CELL UPS01LCD 600VA - works with blazer_usb

[m-stefanski]

Working configuration:

[nutdev1]
  driver = blazer_usb
  port = auto
  norating
  novendor
  protocol = megatec
  default.battery.voltage.high = 13.8
  default.battery.voltage.low = 10.5

I cannot make nutdrv_qx work in any case, but closest to showing something is following config:

[nutdev1]
  driver = "nutdrv_qx"
  protocol = "hunnox"
  port = "auto"
  vendorid = "0001"
  productid = "0000"
  product = "MEC0003"
  vendor = "MEC"
  bus = "003"  

which gives following output:

root@pve:~# /usr/lib/nut/nutdrv_qx -a nutdev1 -DDD
Network UPS Tools - Generic Q* USB/Serial driver 0.32 (2.8.0)
USB communication driver (libusb 1.0) 0.43
   0.000000     [D1] debug level is '3'
   0.000590     [D1] upsdrv_initups...
   0.002361     [D2] Checking device 1 of 6 (1D6B/0003)
   0.002376     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.002378     [D2] Checking device 2 of 6 (0001/0000)
   0.003263     [D2] - VendorID: 0001
   0.003267     [D2] - ProductID: 0000
   0.003269     [D2] - Manufacturer: MEC
   0.003270     [D2] - Product: MEC0003
   0.003272     [D2] - Serial Number: unknown
   0.003274     [D2] - Bus: 003
   0.003275     [D2] - Device: unknown
   0.003277     [D2] - Device release number: 0100
   0.003278     [D2] Trying to match device
   0.003280     [D3] match_function_regex: matching a device...
   0.003322     [D2] Device matches
   0.003326     [D2] Reading first configuration descriptor
   0.003332     [D3] libusb_kernel_driver_active() returned 0
   0.003348     [D2] Claimed interface 0 successfully
   0.003351     [D3] nut_usb_set_altinterface: skipped libusb_set_interface_alt_setting(udev, 0, 0)
   0.003356     [D2] Skipping protocol Voltronic 0.06
   0.003359     [D2] Skipping protocol Voltronic-QS 0.07
   0.003360     [D2] Skipping protocol Voltronic-QS-Hex 0.10
   0.003362     [D2] Skipping protocol Mustek 0.07
   0.003364     [D2] Skipping protocol Megatec/old 0.07
   0.003366     [D2] Skipping protocol BestUPS 0.06
   0.003367     [D2] Skipping protocol Mecer 0.07
   0.003372     [D3] send: Q1
   0.226152     [D3] read: (242.9 000.0 242.7 001 50.1 13.7 --.- 00001001
   0.226175     [D3] send: I
   0.410897     [D3] read: #                                     
   0.410912     [D1] ups_infoval_set: non significant value [ups.firmware]
   0.410917     [D3] send: Q1
   0.633018     [D3] read: (242.9 000.0 243.0 001 50.1 13.7 --.- 00001001
   0.633058     [D3] send: I
   0.817726     [D3] read: #                                     
   0.817742     [D1] ups_infoval_set: non significant value [ups.firmware]
   0.817747     [D3] send: Q1
   1.040236     [D3] read: (243.0 000.0 242.9 001 50.1 13.7 --.- 00001001
   1.040258     [D3] send: I
   1.224779     [D3] read: #                                     
   1.224795     [D1] ups_infoval_set: non significant value [ups.firmware]
   1.224800     [D2] Skipping protocol Zinto 0.06
   1.224802     [D2] Skipping protocol Masterguard 0.02
   1.224808     [D2] Skipping protocol Hunnox 0.01
   1.224809     [D2] Skipping protocol Ablerex 0.01
   1.224811     [D2] Skipping protocol Q1 0.07
   1.224813     Device not supported!
   1.224814     Device not supported!

nut-scanner:

root@pve:~# nut-scanner -U
Scanning USB bus.
[nutdev1]
        driver = "nutdrv_qx"
        port = "auto"
        vendorid = "0001"
        productid = "0000"
        product = "MEC0003"
        vendor = "MEC"
        bus = "003"

[jimklimov]

The first-order question is whether you can try with current NUT code base? Release v2.8.3 is out for some time, and there were some master-branch fixes after it already.

See e.g. https://github.com/networkupstools/nut/wiki/Building-NUT-for-in%E2%80%90place-upgrades-or-non%E2%80%90disruptive-tests for details; note that to test the built code you don't have to install it until you are satisfied that it works (better) :)

[m-stefanski]

Thank you - I have just checked with 2.8.3 and using command ./drivers/nutdrv_qx -a nutdev1 -d1 -DDDDDD -x protocol=q1 and configuration from post 1 i got it to display parameters:

root@pve:~/nut-2.8.3/nut# ./drivers/nutdrv_qx -a nutdev1 -d1 -DDDDDD -x protocol=q1
   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000089     [D1] upsdrv_makevartable...
   0.000108     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000117     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000192     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000195     [D5] do_upsconf_args: call do_global_args()
   0.000201     [D3] do_global_args: var='maxretry' val='3'
   0.000242     [D5] do_upsconf_args: confupsname=nutdev1, var=driver, val=nutdrv_qx
   0.000245     [D5] do_upsconf_args: call main_arg()
   0.000250     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000253     [D5] do_upsconf_args: not a main_arg()
   0.000256     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000264     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000266     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000270     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000275     [D5] do_upsconf_args: confupsname=nutdev1, var=protocol, val=megatec
   0.000278     [D5] do_upsconf_args: call main_arg()
   0.000281     [D3] main_arg: var='protocol' val='megatec'
   0.000284     [D5] do_upsconf_args: not a main_arg()
   0.000287     [D5] do_upsconf_args: process as value
   0.000290     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000294     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'megatec', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000297     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000302     [D5] send_to_all: SETINFO driver.parameter.protocol "megatec"
   0.000306     [D5] do_upsconf_args: confupsname=nutdev1, var=port, val=auto
   0.000309     [D5] do_upsconf_args: call main_arg()
   0.000311     [D3] main_arg: var='port' val='auto'
   0.000315     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000317     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000320     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000325     [D5] do_upsconf_args: confupsname=nutdev1, var=vendorid, val=0001
   0.000327     [D5] do_upsconf_args: call main_arg()
   0.000330     [D3] main_arg: var='vendorid' val='0001'
   0.000332     [D5] do_upsconf_args: not a main_arg()
   0.000334     [D5] do_upsconf_args: process as value
   0.000337     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000340     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000346     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000350     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000354     [D5] do_upsconf_args: confupsname=nutdev1, var=productid, val=0000
   0.000357     [D5] do_upsconf_args: call main_arg()
   0.000359     [D3] main_arg: var='productid' val='0000'
   0.000362     [D5] do_upsconf_args: not a main_arg()
   0.000365     [D5] do_upsconf_args: process as value
   0.000369     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000372     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000374     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000378     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000382     [D5] do_upsconf_args: confupsname=nutdev1, var=product, val=MEC0003
   0.000385     [D5] do_upsconf_args: call main_arg()
   0.000387     [D3] main_arg: var='product' val='MEC0003'
   0.000391     [D5] do_upsconf_args: not a main_arg()
   0.000395     [D5] do_upsconf_args: process as value
   0.000398     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000401     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000404     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000407     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000411     [D5] do_upsconf_args: confupsname=nutdev1, var=vendor, val=MEC
   0.000413     [D5] do_upsconf_args: call main_arg()
   0.000416     [D3] main_arg: var='vendor' val='MEC'
   0.000419     [D5] do_upsconf_args: not a main_arg()
   0.000421     [D5] do_upsconf_args: process as value
   0.000427     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000430     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000432     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000439     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000443     [D5] do_upsconf_args: confupsname=nutdev1, var=bus, val=003
   0.000445     [D5] do_upsconf_args: call main_arg()
   0.000448     [D3] main_arg: var='bus' val='003'
   0.000450     [D5] do_upsconf_args: not a main_arg()
   0.000452     [D5] do_upsconf_args: process as value
   0.000456     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000459     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000462     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000465     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000474     [D3] main_arg: var='protocol' val='q1'
   0.000478     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000482     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000490     [D1] debug level is '6'
   0.000493     [D5] send_to_all: SETINFO driver.debug "6"
   0.000499     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.001125     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.001138     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.001141     [D1] Signalling UPS [nutdev1]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.001146     Can't open /var/state/ups/nutdrv_qx-nutdev1: No such file or directory
   0.001152     [D1] Request for other driver to exit returned code -1
   0.001155     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.001159     [D5] send_to_all: SETINFO device.type "ups"
   0.001162     [D5] send_to_all: SETINFO driver.state "init.device"
   0.001164     [D1] upsdrv_initups...
   0.003237     [D2] Checking device 1 of 6 (1D6B/0003)
   0.003255     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.003259     [D2] Checking device 2 of 6 (0001/0000)
   0.004157     [D2] - VendorID: 0001
   0.004162     [D2] - ProductID: 0000
   0.004164     [D2] - Manufacturer: MEC
   0.004166     [D2] - Product: MEC0003
   0.004173     [D2] - Serial Number: unknown
   0.004176     [D2] - Bus: 003
   0.004177     [D2] - Bus Port: 008
   0.004180     [D2] - Device: 008
   0.004182     [D2] - Device release number: 0100
   0.004185     [D2] Trying to match device
   0.004187     [D3] match_function_regex: matching a device...
   0.004215     [D2] Device matches
   0.004218     [D2] Reading configuration descriptor 1 of 1
   0.004229     [D3] libusb_kernel_driver_active() returned 0: Success
   0.004243     [D2] Claimed interface 0 successfully
   0.004248     [D3] nut_libusb_set_altinterface: skipped libusb_set_interface_alt_setting(udev, 0, 0)
   0.004254     [D5] send_to_all: SETINFO ups.vendorid "0001"
   0.004258     [D5] send_to_all: SETINFO ups.productid "0000"
   0.004262     [D2] Skipping protocol Voltronic 0.11
   0.004265     [D2] Skipping protocol Voltronic-QS 0.10
   0.004268     [D2] Skipping protocol Voltronic-QS-Hex 0.11
   0.004270     [D2] Skipping protocol Mustek 0.08
   0.004274     [D2] Skipping protocol Megatec/old 0.08
   0.004277     [D2] Skipping protocol BestUPS 0.08
   0.004280     [D2] Skipping protocol Mecer 0.09
   0.004283     [D2] Skipping protocol Megatec 0.07
   0.004287     [D2] Skipping protocol Zinto 0.07
   0.004289     [D2] Skipping protocol Masterguard 0.03
   0.004292     [D2] Skipping protocol Hunnox 0.02
   0.004295     [D2] Skipping protocol Ablerex 0.02
   0.004297     [D2] Skipping protocol INNOVART31 0.02
   0.004300     [D2] Skipping protocol INNOVART33 0.01
   0.004303     [D2] Skipping protocol Q2 0.01
   0.004306     [D2] Skipping protocol Q6 0.01
   0.004308     [D2] Skipping protocol Gtec 0.01
   0.004312     [D3] send: Q1
   0.004314     [D4] command index: 0x03
   0.226636     [D5] read: (47 bytes) => 28 32 34 34 2e 30 20 30 30 30 2e 30 20 32 34 34 2e 30
   0.226650     [D5]  20 30 30 31 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.226653     [D5]  30 30 31 0d
   0.226657     [D3] read: (244.0 000.0 244.0 001 50.1 13.7 --.- 00001001
   0.226665     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.226668     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.226674     [D5] send_to_all: SETINFO input.voltage "244.0"
   0.226677     Using protocol: Q1 0.08
   0.226682     [D5] send_to_all: SETINFO driver.state "init.quiet"
   0.226688     [D5] send_to_all: SETINFO driver.version "2.8.3.285-285+g6393115cb"
   0.226691     [D5] send_to_all: SETINFO driver.version.internal "0.43"
   0.226695     [D5] send_to_all: SETINFO driver.name "nutdrv_qx"
   0.226697     [D5] send_to_all: SETINFO driver.state "init.info"
   0.226701     [D1] upsdrv_initinfo...
   0.226704     [D5] send_to_all: SETINFO driver.version.data "Q1 0.08"
   0.226709     [D3] send: Q1
   0.226713     [D4] command index: 0x03
   0.449211     [D5] read: (47 bytes) => 28 32 34 34 2e 32 20 30 30 30 2e 30 20 32 34 34 2e 32
   0.449226     [D5]  20 30 30 31 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.449228     [D5]  30 30 31 0d
   0.449230     [D3] read: (244.2 000.0 244.2 001 50.1 13.7 --.- 00001001
   0.449235     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.449236     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.449266     [D5] send_to_all: SETINFO input.voltage.fault "0.0"
   0.449271     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.449273     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.449277     [D5] send_to_all: SETINFO output.voltage "244.2"
   0.449280     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.449283     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '0'
   0.449286     [D5] send_to_all: SETINFO ups.load "1"
   0.449290     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.449292     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.449296     [D5] send_to_all: SETINFO input.frequency "50.1"
   0.449300     [D5] send_to_all: SETINFO battery.voltage "13.7"
   0.449303     [D2] ups_infoval_set: non numerical value [ups.temperature: --.-]
   0.449306     [D5] update_status: OL
   0.449308     [D5] update_status: !LB
   0.449313     [D5] send_to_all: SETINFO ups.type "offline / line interactive"
   0.449317     [D5] update_status: !CAL
   0.449320     [D5] update_status: !FSD
   0.449325     [D5] send_to_all: SETINFO ups.beeper.status "enabled"
   0.449328     [D5] send_to_all: ADDCMD beeper.toggle
   0.449330     [D5] send_to_all: ADDCMD load.off
   0.449333     [D5] send_to_all: ADDCMD load.on
   0.449335     [D5] send_to_all: ADDCMD shutdown.return
   0.449338     [D5] send_to_all: ADDCMD shutdown.stayoff
   0.449341     [D5] send_to_all: ADDCMD shutdown.stop
   0.449344     [D5] send_to_all: ADDCMD test.battery.start
   0.449346     [D5] send_to_all: ADDCMD test.battery.start.deep
   0.449350     [D5] send_to_all: ADDCMD test.battery.start.quick
   0.449352     [D5] send_to_all: ADDCMD test.battery.stop
   0.449356     [D5] send_to_all: SETINFO ups.delay.start "180"
   0.449359     [D5] send_to_all: SETFLAGS ups.delay.start RW
   0.449363     [D5] send_to_all: ADDRANGE ups.delay.start 0 599940
   0.449366     [D5] send_to_all: SETFLAGS ups.delay.start RW NUMBER
   0.449369     [D5] send_to_all: SETINFO ups.delay.shutdown "30"
   0.449375     [D5] send_to_all: SETFLAGS ups.delay.shutdown RW
   0.449378     [D5] send_to_all: ADDRANGE ups.delay.shutdown 12 600
   0.449380     [D5] send_to_all: SETFLAGS ups.delay.shutdown RW NUMBER
   0.449385     Can't autodetect number of battery packs [-1/13.70]
   0.449392     Battery runtime will not be calculated (runtimecal not set)
   0.449396     [D5] send_to_all: SETINFO driver.parameter.pollfreq "30"
   0.449403     [D5] send_to_all: ADDCMD shutdown.default
   0.449407     [D5] send_to_all: SETINFO driver.state "init.updateinfo"
   0.449410     [D1] upsdrv_updateinfo...
   0.449412     [D1] Quick update...
   0.449415     [D3] send: Q1
   0.449418     [D4] command index: 0x03
   0.671723     [D5] read: (47 bytes) => 28 32 34 34 2e 31 20 30 30 30 2e 30 20 32 34 34 2e 32
   0.671738     [D5]  20 30 30 31 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.671741     [D5]  30 30 31 0d
   0.671744     [D3] read: (244.1 000.0 244.2 001 50.1 13.7 --.- 00001001
   0.671749     [D5] update_status: OL
   0.671753     [D5] update_status: !LB
   0.671760     [D5] update_status: !CAL
   0.671763     [D5] update_status: !FSD
   0.671770     [D5] send_to_all: SETINFO ups.status "OL"
   0.671773     [D5] send_to_all: DATAOK
   0.671776     [D5] send_to_all: SETINFO driver.state "init.quiet"
   0.671780     [D5] send_to_all: SETINFO driver.parameter.pollinterval "2"
   0.671783     [D5] send_to_all: SETINFO driver.parameter.synchronous "auto"
   0.671787     Running as foreground process, not saving a PID file
   0.671790     [D5] send_to_all: SETINFO driver.flag.allow_killpower "0"
   0.671793     [D5] send_to_all: SETFLAGS driver.flag.allow_killpower RW NUMBER
   0.671796     [D5] send_to_all: ADDCMD driver.killpower
   0.671798     [D5] send_to_all: ADDCMD driver.reload
   0.671801     [D5] send_to_all: ADDCMD driver.reload-or-exit
   0.671803     [D5] send_to_all: ADDCMD driver.reload-or-error
   0.671806     [D5] send_to_all: SETINFO driver.state "quiet"
   0.671808     [D1] Driver initialization completed, beginning data dump (1 loops)
   0.671811     [D5] send_to_all: SETINFO driver.state "updateinfo"
   0.671813     [D1] upsdrv_updateinfo...
   0.671815     [D1] Quick update...
   0.671818     [D3] send: Q1
   0.671820     [D4] command index: 0x03
   0.894258     [D5] read: (47 bytes) => 28 32 34 34 2e 32 20 30 30 30 2e 30 20 32 34 34 2e 33
   0.894273     [D5]  20 30 30 31 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.894276     [D5]  30 30 31 0d
   0.894280     [D3] read: (244.2 000.0 244.3 001 50.1 13.7 --.- 00001001
   0.894285     [D5] update_status: OL
   0.894288     [D5] update_status: !LB
   0.894291     [D5] update_status: !CAL
   0.894294     [D5] update_status: !FSD
   0.894301     [D5] send_to_all: SETINFO driver.state "quiet"
   0.894304     [D5] send_to_all: SETINFO driver.state "updateinfo"
   0.894306     [D1] upsdrv_updateinfo...
   0.894309     [D1] Quick update...
   0.894312     [D3] send: Q1
   0.894316     [D4] command index: 0x03
   1.116430     [D5] read: (47 bytes) => 28 32 34 34 2e 33 20 30 30 30 2e 30 20 32 34 34 2e 33
   1.116445     [D5]  20 30 30 31 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   1.116447     [D5]  30 30 31 0d
   1.116449     [D3] read: (244.3 000.0 244.3 001 50.1 13.7 --.- 00001001
   1.116453     [D5] update_status: OL
   1.116455     [D5] update_status: !LB
   1.116458     [D5] update_status: !CAL
   1.116460     [D5] update_status: !FSD
   1.116489     [D5] send_to_all: SETINFO driver.state "quiet"
   1.116491     [D5] send_to_all: SETINFO driver.state "dumping"
   1.116493     [D3] Entering dstate_dump
battery.voltage: 13.7
device.type: ups
driver.debug: 6
driver.flag.allow_killpower: 0
driver.name: nutdrv_qx
driver.parameter.bus: 003
driver.parameter.pollfreq: 30
driver.parameter.pollinterval: 2
driver.parameter.port: auto
driver.parameter.product: MEC0003
driver.parameter.productid: 0000
driver.parameter.protocol: q1
driver.parameter.synchronous: auto
driver.parameter.vendor: MEC
driver.parameter.vendorid: 0001
driver.state: dumping
driver.version: 2.8.3.285-285+g6393115cb
driver.version.data: Q1 0.08
driver.version.internal: 0.43
driver.version.usb: libusb-1.0.26 (API: 0x01000109)
input.frequency: 50.1
input.voltage: 244.0
input.voltage.fault: 0.0
output.voltage: 244.2
ups.beeper.status: enabled
ups.delay.shutdown: 30
ups.delay.start: 180
ups.load: 1
ups.productid: 0000
ups.status: OL
ups.type: offline / line interactive
ups.vendorid: 0001
   1.116538     [D5] send_to_all: SETINFO driver.state "cleanup.upsdrv"
   1.116543     [D1] upsdrv_cleanup...
   1.116694     [D5] send_to_all: SETINFO driver.state "cleanup.exit"

However, it worked 2 times and now it is failing with error:

root@pve:~/nut-2.8.3/nut# ./drivers/nutdrv_qx -a nutdev1 -d1 -DDDDDD -x protocol=q1
   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000060     [D1] upsdrv_makevartable...
   0.000098     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000101     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000161     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000165     [D5] do_upsconf_args: call do_global_args()
   0.000173     [D3] do_global_args: var='maxretry' val='3'
   0.000211     [D5] do_upsconf_args: confupsname=nutdev1, var=driver, val=nutdrv_qx
   0.000213     [D5] do_upsconf_args: call main_arg()
   0.000216     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000218     [D5] do_upsconf_args: not a main_arg()
   0.000220     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000222     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000224     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000226     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000229     [D5] do_upsconf_args: confupsname=nutdev1, var=protocol, val=q1
   0.000232     [D5] do_upsconf_args: call main_arg()
   0.000233     [D3] main_arg: var='protocol' val='q1'
   0.000235     [D5] do_upsconf_args: not a main_arg()
   0.000236     [D5] do_upsconf_args: process as value
   0.000238     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000241     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'q1', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000243     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000247     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000251     [D5] do_upsconf_args: confupsname=nutdev1, var=port, val=auto
   0.000252     [D5] do_upsconf_args: call main_arg()
   0.000254     [D3] main_arg: var='port' val='auto'
   0.000256     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000257     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000261     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000265     [D5] do_upsconf_args: confupsname=nutdev1, var=vendorid, val=0001
   0.000268     [D5] do_upsconf_args: call main_arg()
   0.000269     [D3] main_arg: var='vendorid' val='0001'
   0.000271     [D5] do_upsconf_args: not a main_arg()
   0.000273     [D5] do_upsconf_args: process as value
   0.000276     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000278     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000280     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000282     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000286     [D5] do_upsconf_args: confupsname=nutdev1, var=productid, val=0000
   0.000288     [D5] do_upsconf_args: call main_arg()
   0.000290     [D3] main_arg: var='productid' val='0000'
   0.000292     [D5] do_upsconf_args: not a main_arg()
   0.000296     [D5] do_upsconf_args: process as value
   0.000298     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000301     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000304     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000307     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000311     [D5] do_upsconf_args: confupsname=nutdev1, var=product, val=MEC0003
   0.000315     [D5] do_upsconf_args: call main_arg()
   0.000318     [D3] main_arg: var='product' val='MEC0003'
   0.000320     [D5] do_upsconf_args: not a main_arg()
   0.000322     [D5] do_upsconf_args: process as value
   0.000325     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000328     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000330     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000334     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000338     [D5] do_upsconf_args: confupsname=nutdev1, var=vendor, val=MEC
   0.000341     [D5] do_upsconf_args: call main_arg()
   0.000343     [D3] main_arg: var='vendor' val='MEC'
   0.000345     [D5] do_upsconf_args: not a main_arg()
   0.000348     [D5] do_upsconf_args: process as value
   0.000350     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000353     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000356     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000363     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000366     [D5] do_upsconf_args: confupsname=nutdev1, var=bus, val=003
   0.000368     [D5] do_upsconf_args: call main_arg()
   0.000371     [D3] main_arg: var='bus' val='003'
   0.000378     [D5] do_upsconf_args: not a main_arg()
   0.000380     [D5] do_upsconf_args: process as value
   0.000383     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000387     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000389     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000392     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000400     [D3] main_arg: var='protocol' val='q1'
   0.000404     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000411     [D1] debug level is '6'
   0.000415     [D5] send_to_all: SETINFO driver.debug "6"
   0.000418     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.000969     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.000979     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.000982     [D1] Signalling UPS [nutdev1]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.000986     Can't open /var/state/ups/nutdrv_qx-nutdev1: No such file or directory
   0.000989     [D1] Request for other driver to exit returned code -1
   0.000991     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.000994     [D5] send_to_all: SETINFO device.type "ups"
   0.000996     [D5] send_to_all: SETINFO driver.state "init.device"
   0.000998     [D1] upsdrv_initups...
   0.002834     [D2] Checking device 1 of 6 (1D6B/0003)
   0.002850     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.002853     [D2] Checking device 2 of 6 (0001/0000)
   0.003795     [D2] - VendorID: 0001
   0.003804     [D2] - ProductID: 0000
   0.003805     [D2] - Manufacturer: MEC
   0.003807     [D2] - Product: MEC0003
   0.003808     [D2] - Serial Number: unknown
   0.003809     [D2] - Bus: 003
   0.003813     [D2] - Bus Port: 008
   0.003814     [D2] - Device: 008
   0.003815     [D2] - Device release number: 0100
   0.003817     [D2] Trying to match device
   0.003818     [D3] match_function_regex: matching a device...
   0.003858     [D2] Device matches
   0.003863     [D2] Reading configuration descriptor 1 of 1
   0.003874     [D3] libusb_kernel_driver_active() returned 0: Success
   0.003879     [D2] failed to claim USB device: Resource busy
   0.003883     [D2] Kernel driver already detached
   0.003886     [D2] failed to claim USB device: Resource busy
   0.003888     [D2] Kernel driver already detached
   0.003891     [D2] failed to claim USB device: Resource busy
   0.003898     [D2] Kernel driver already detached
   0.003900     [D2] failed to claim USB device: Resource busy
   0.003902     [D2] Kernel driver already detached
   0.003906     Can't claim USB device [0001:0000]@0/0/0: Entity not found
   0.003911     [D5] send_to_all: SETINFO driver.state "cleanup.exit"

Old blazer_usb with 2.8.0 still works, I am not sure what causes this inconsistency

[jimklimov]

Most likely the "Resource busy" means that a systemd managed instance of the driver appeared and grabbed the devfs node (see wiki for NDE, I can't link right now).

• UPDATE: https://github.com/networkupstools/nut/wiki/nut%E2%80%90driver%E2%80%90enumerator-(NDE)

Although the driver does seem to try contacting an earlier instance by socket and pid file, and can't...

Maybe the controller hung, so if there is no other driver program running - try to re-plug the cable and/or a software equivalent of that (e.g. check scripts/usb_resetter)...

[m-stefanski]

I have re-plugged UPS to ensure the reset was done properly and tested several drivers.

Surprisingly it only works when I specify "megatec" in config file but override it with "q1" in command line.

[ups]                                                                                                                                            >
  driver = "nutdrv_qx"                                                                                                                           >
  protocol = "megatec"                                                                                                                           >
  port = "auto"                                                                                                                                  >
  vendorid = "0001"                                                                                                                              >
  productid = "0000"                                                                                                                             >
  product = "MEC0003"                                                                                                                            >
  vendor = "MEC"                                                                                                                                 >
  bus = "003"

./drivers/nutdrv_qx -a ups -d1 -DDDDDD -x protocol=q1

   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000052     [D1] upsdrv_makevartable...
   0.000067     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000070     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000136     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000140     [D5] do_upsconf_args: call do_global_args()
   0.000144     [D3] do_global_args: var='maxretry' val='3'
   0.000183     [D5] do_upsconf_args: confupsname=ups, var=driver, val=nutdrv_qx
   0.000185     [D5] do_upsconf_args: call main_arg()
   0.000186     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000188     [D5] do_upsconf_args: not a main_arg()
   0.000189     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000191     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000193     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000195     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000199     [D5] do_upsconf_args: confupsname=ups, var=protocol, val=megatec
   0.000201     [D5] do_upsconf_args: call main_arg()
   0.000203     [D3] main_arg: var='protocol' val='megatec'
   0.000206     [D5] do_upsconf_args: not a main_arg()
   0.000207     [D5] do_upsconf_args: process as value
   0.000213     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000216     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'megatec', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000219     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000223     [D5] send_to_all: SETINFO driver.parameter.protocol "megatec"
   0.000229     [D5] do_upsconf_args: confupsname=ups, var=port, val=auto
   0.000231     [D5] do_upsconf_args: call main_arg()
   0.000233     [D3] main_arg: var='port' val='auto'
   0.000235     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000238     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000241     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000245     [D5] do_upsconf_args: confupsname=ups, var=vendorid, val=0001
   0.000248     [D5] do_upsconf_args: call main_arg()
   0.000250     [D3] main_arg: var='vendorid' val='0001'
   0.000251     [D5] do_upsconf_args: not a main_arg()
   0.000253     [D5] do_upsconf_args: process as value
   0.000256     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000259     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000261     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000264     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000269     [D5] do_upsconf_args: confupsname=ups, var=productid, val=0000
   0.000272     [D5] do_upsconf_args: call main_arg()
   0.000274     [D3] main_arg: var='productid' val='0000'
   0.000277     [D5] do_upsconf_args: not a main_arg()
   0.000279     [D5] do_upsconf_args: process as value
   0.000281     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000284     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000286     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000289     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000293     [D5] do_upsconf_args: confupsname=ups, var=product, val=MEC0003
   0.000295     [D5] do_upsconf_args: call main_arg()
   0.000298     [D3] main_arg: var='product' val='MEC0003'
   0.000300     [D5] do_upsconf_args: not a main_arg()
   0.000302     [D5] do_upsconf_args: process as value
   0.000305     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000308     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000310     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000314     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000318     [D5] do_upsconf_args: confupsname=ups, var=vendor, val=MEC
   0.000320     [D5] do_upsconf_args: call main_arg()
   0.000322     [D3] main_arg: var='vendor' val='MEC'
   0.000324     [D5] do_upsconf_args: not a main_arg()
   0.000326     [D5] do_upsconf_args: process as value
   0.000329     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000332     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000334     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000342     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000345     [D5] do_upsconf_args: confupsname=ups, var=bus, val=003
   0.000347     [D5] do_upsconf_args: call main_arg()
   0.000348     [D3] main_arg: var='bus' val='003'
   0.000350     [D5] do_upsconf_args: not a main_arg()
   0.000351     [D5] do_upsconf_args: process as value
   0.000353     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000355     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000356     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000359     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000366     [D3] main_arg: var='protocol' val='q1'
   0.000373     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000374     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000380     [D1] debug level is '6'
   0.000382     [D5] send_to_all: SETINFO driver.debug "6"
   0.000387     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.000998     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.001012     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.001015     [D1] Signalling UPS [ups]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.001025     Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory
   0.001027     [D1] Request for other driver to exit returned code -1
   0.001029     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.001033     [D5] send_to_all: SETINFO device.type "ups"
   0.001036     [D5] send_to_all: SETINFO driver.state "init.device"
   0.001038     [D1] upsdrv_initups...
   0.003038     [D2] Checking device 1 of 6 (1D6B/0003)
   0.003062     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.003065     [D2] Checking device 2 of 6 (0001/0000)
   0.003916     [D2] - VendorID: 0001
   0.003920     [D2] - ProductID: 0000
   0.003922     [D2] - Manufacturer: MEC
   0.003924     [D2] - Product: MEC0003
   0.003925     [D2] - Serial Number: unknown
   0.003927     [D2] - Bus: 003
   0.003928     [D2] - Bus Port: 008
   0.003930     [D2] - Device: 005
   0.003932     [D2] - Device release number: 0100
   0.003933     [D2] Trying to match device
   0.003935     [D3] match_function_regex: matching a device...
   0.003961     [D2] Device matches
   0.003964     [D2] Reading configuration descriptor 1 of 1
   0.003974     [D3] libusb_kernel_driver_active() returned 0: Success
   0.003988     [D2] Claimed interface 0 successfully
   0.003996     [D3] nut_libusb_set_altinterface: skipped libusb_set_interface_alt_setting(udev, 0, 0)
   0.004003     [D5] send_to_all: SETINFO ups.vendorid "0001"
   0.004006     [D5] send_to_all: SETINFO ups.productid "0000"
   0.004010     [D2] Skipping protocol Voltronic 0.11
   0.004012     [D2] Skipping protocol Voltronic-QS 0.10
   0.004014     [D2] Skipping protocol Voltronic-QS-Hex 0.11
   0.004021     [D2] Skipping protocol Mustek 0.08
   0.004023     [D2] Skipping protocol Megatec/old 0.08
   0.004025     [D2] Skipping protocol BestUPS 0.08
   0.004027     [D2] Skipping protocol Mecer 0.09
   0.004029     [D2] Skipping protocol Megatec 0.07
   0.004031     [D2] Skipping protocol Zinto 0.07
   0.004033     [D2] Skipping protocol Masterguard 0.03
   0.004036     [D2] Skipping protocol Hunnox 0.02
   0.004038     [D2] Skipping protocol Ablerex 0.02
   0.004041     [D2] Skipping protocol INNOVART31 0.02
   0.004049     [D2] Skipping protocol INNOVART33 0.01
   0.004052     [D2] Skipping protocol Q2 0.01
   0.004055     [D2] Skipping protocol Q6 0.01
   0.004057     [D2] Skipping protocol Gtec 0.01
   0.004062     [D3] send: Q1
   0.004064     [D4] command index: 0x03
   0.226117     [D5] read: (47 bytes) => 28 32 34 34 2e 36 20 32 34 36 2e 30 20 32 34 34 2e 36
   0.226131     [D5]  20 30 31 33 20 35 30 2e 30 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.226134     [D5]  30 30 31 0d
   0.226136     [D3] read: (244.6 246.0 244.6 013 50.0 13.7 --.- 00001001
   0.226144     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.226146     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.226153     [D5] send_to_all: SETINFO input.voltage "244.6"
   0.226155     Using protocol: Q1 0.08
   0.226159     [D5] send_to_all: SETINFO driver.state "init.quiet"
   0.226162     [D5] send_to_all: SETINFO driver.version "2.8.3.285-285+g6393115cb"
   0.226171     [D5] send_to_all: SETINFO driver.version.internal "0.43"
   0.226174     [D5] send_to_all: SETINFO driver.name "nutdrv_qx"
   0.226176     [D5] send_to_all: SETINFO driver.state "init.info"
   0.226179     [D1] upsdrv_initinfo...
   0.226181     [D5] send_to_all: SETINFO driver.version.data "Q1 0.08"
   0.226187     [D3] send: Q1
   0.226189     [D4] command index: 0x03
   0.448309     [D5] read: (47 bytes) => 28 32 34 34 2e 35 20 32 34 36 2e 30 20 32 34 34 2e 35
   0.448323     [D5]  20 30 31 35 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.448325     [D5]  30 30 31 0d
   0.448327     [D3] read: (244.5 246.0 244.5 015 50.1 13.7 --.- 00001001
   0.448332     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.448333     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.448364     [D5] send_to_all: SETINFO input.voltage.fault "246.0"
   0.448368     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.448369     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.448372     [D5] send_to_all: SETINFO output.voltage "244.5"
   0.448375     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.448376     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '0'
   0.448379     [D5] send_to_all: SETINFO ups.load "15"
   0.448381     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.448382     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.448385     [D5] send_to_all: SETINFO input.frequency "50.1"
   0.448388     [D5] send_to_all: SETINFO battery.voltage "13.7"
   0.448391     [D2] ups_infoval_set: non numerical value [ups.temperature: --.-]
   0.448393     [D5] update_status: OL
   0.448395     [D5] update_status: !LB
   0.448399     [D5] send_to_all: SETINFO ups.type "offline / line interactive"
   0.448401     [D5] update_status: !CAL
   0.448403     [D5] update_status: !FSD
   0.448406     [D5] send_to_all: SETINFO ups.beeper.status "enabled"
   0.448410     [D5] send_to_all: ADDCMD beeper.toggle
   0.448412     [D5] send_to_all: ADDCMD load.off
   0.448414     [D5] send_to_all: ADDCMD load.on
   0.448416     [D5] send_to_all: ADDCMD shutdown.return
   0.448420     [D5] send_to_all: ADDCMD shutdown.stayoff
   0.448423     [D5] send_to_all: ADDCMD shutdown.stop
   0.448425     [D5] send_to_all: ADDCMD test.battery.start
   0.448428     [D5] send_to_all: ADDCMD test.battery.start.deep
   0.448430     [D5] send_to_all: ADDCMD test.battery.start.quick
   0.448436     [D5] send_to_all: ADDCMD test.battery.stop
   0.448439     [D5] send_to_all: SETINFO ups.delay.start "180"
   0.448442     [D5] send_to_all: SETFLAGS ups.delay.start RW
   0.448445     [D5] send_to_all: ADDRANGE ups.delay.start 0 599940
   0.448447     [D5] send_to_all: SETFLAGS ups.delay.start RW NUMBER
   0.448451     [D5] send_to_all: SETINFO ups.delay.shutdown "30"
   0.448454     [D5] send_to_all: SETFLAGS ups.delay.shutdown RW
   0.448457     [D5] send_to_all: ADDRANGE ups.delay.shutdown 12 600
   0.448459     [D5] send_to_all: SETFLAGS ups.delay.shutdown RW NUMBER
   0.448465     Can't autodetect number of battery packs [-1/13.70]
   0.448468     Battery runtime will not be calculated (runtimecal not set)
   0.448472     [D5] send_to_all: SETINFO driver.parameter.pollfreq "30"
   0.448479     [D5] send_to_all: ADDCMD shutdown.default
   0.448483     [D5] send_to_all: SETINFO driver.state "init.updateinfo"
   0.448485     [D1] upsdrv_updateinfo...
   0.448488     [D1] Quick update...
   0.448490     [D3] send: Q1
   0.448493     [D4] command index: 0x03
   0.670535     [D5] read: (47 bytes) => 28 32 34 34 2e 36 20 32 34 36 2e 30 20 32 34 34 2e 35
   0.670549     [D5]  20 30 31 32 20 35 30 2e 30 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.670551     [D5]  30 30 31 0d
   0.670553     [D3] read: (244.6 246.0 244.5 012 50.0 13.7 --.- 00001001
   0.670557     [D5] update_status: OL
   0.670565     [D5] update_status: !LB
   0.670568     [D5] update_status: !CAL
   0.670569     [D5] update_status: !FSD
   0.670575     [D5] send_to_all: SETINFO ups.status "OL"
   0.670577     [D5] send_to_all: DATAOK
   0.670579     [D5] send_to_all: SETINFO driver.state "init.quiet"
   0.670582     [D5] send_to_all: SETINFO driver.parameter.pollinterval "2"
   0.670584     [D5] send_to_all: SETINFO driver.parameter.synchronous "auto"
   0.670587     Running as foreground process, not saving a PID file
   0.670589     [D5] send_to_all: SETINFO driver.flag.allow_killpower "0"
   0.670590     [D5] send_to_all: SETFLAGS driver.flag.allow_killpower RW NUMBER
   0.670592     [D5] send_to_all: ADDCMD driver.killpower
   0.670594     [D5] send_to_all: ADDCMD driver.reload
   0.670595     [D5] send_to_all: ADDCMD driver.reload-or-exit
   0.670597     [D5] send_to_all: ADDCMD driver.reload-or-error
   0.670599     [D5] send_to_all: SETINFO driver.state "quiet"
   0.670600     [D1] Driver initialization completed, beginning data dump (1 loops)
   0.670602     [D5] send_to_all: SETINFO driver.state "updateinfo"
   0.670603     [D1] upsdrv_updateinfo...
   0.670606     [D1] Quick update...
   0.670608     [D3] send: Q1
   0.670609     [D4] command index: 0x03
   0.892635     [D5] read: (47 bytes) => 28 32 34 34 2e 35 20 32 34 36 2e 30 20 32 34 34 2e 34
   0.892654     [D5]  20 30 31 33 20 35 30 2e 30 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.892656     [D5]  30 30 31 0d
   0.892658     [D3] read: (244.5 246.0 244.4 013 50.0 13.7 --.- 00001001
   0.892662     [D5] update_status: OL
   0.892665     [D5] update_status: !LB
   0.892682     [D5] update_status: !CAL
   0.892684     [D5] update_status: !FSD
   0.892692     [D5] send_to_all: SETINFO driver.state "quiet"
   0.892696     [D5] send_to_all: SETINFO driver.state "updateinfo"
   0.892704     [D1] upsdrv_updateinfo...
   0.892708     [D1] Quick update...
   0.892712     [D3] send: Q1
   0.892715     [D4] command index: 0x03
   1.114631     [D5] read: (47 bytes) => 28 32 34 34 2e 35 20 32 34 36 2e 30 20 32 34 34 2e 34
   1.114646     [D5]  20 30 31 34 20 35 30 2e 30 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   1.114648     [D5]  30 30 31 0d
   1.114650     [D3] read: (244.5 246.0 244.4 014 50.0 13.7 --.- 00001001
   1.114655     [D5] update_status: OL
   1.114657     [D5] update_status: !LB
   1.114660     [D5] update_status: !CAL
   1.114663     [D5] update_status: !FSD
   1.114669     [D5] send_to_all: SETINFO driver.state "quiet"
   1.114681     [D5] send_to_all: SETINFO driver.state "dumping"
   1.114689     [D3] Entering dstate_dump
battery.voltage: 13.7
device.type: ups
driver.debug: 6
driver.flag.allow_killpower: 0
driver.name: nutdrv_qx
driver.parameter.bus: 003
driver.parameter.pollfreq: 30
driver.parameter.pollinterval: 2
driver.parameter.port: auto
driver.parameter.product: MEC0003
driver.parameter.productid: 0000
driver.parameter.protocol: q1
driver.parameter.synchronous: auto
driver.parameter.vendor: MEC
driver.parameter.vendorid: 0001
driver.state: dumping
driver.version: 2.8.3.285-285+g6393115cb
driver.version.data: Q1 0.08
driver.version.internal: 0.43
driver.version.usb: libusb-1.0.26 (API: 0x01000109)
input.frequency: 50.1
input.voltage: 244.6
input.voltage.fault: 246.0
output.voltage: 244.5
ups.beeper.status: enabled
ups.delay.shutdown: 30
ups.delay.start: 180
ups.load: 15
ups.productid: 0000
ups.status: OL
ups.type: offline / line interactive
ups.vendorid: 0001
   1.114751     [D5] send_to_all: SETINFO driver.state "cleanup.upsdrv"
   1.114755     [D1] upsdrv_cleanup...
   1.114913     [D5] send_to_all: SETINFO driver.state "cleanup.exit"

Note that in config file protocol is megatec and in command line it is q1.

HOWEVER when I change protocol in config file to q1 I get the following error:

   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000069     [D1] upsdrv_makevartable...
   0.000082     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000085     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000134     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000137     [D5] do_upsconf_args: call do_global_args()
   0.000140     [D3] do_global_args: var='maxretry' val='3'
   0.000178     [D5] do_upsconf_args: confupsname=ups, var=driver, val=nutdrv_qx
   0.000180     [D5] do_upsconf_args: call main_arg()
   0.000181     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000183     [D5] do_upsconf_args: not a main_arg()
   0.000187     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000190     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000193     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000194     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000198     [D5] do_upsconf_args: confupsname=ups, var=protocol, val=q1
   0.000200     [D5] do_upsconf_args: call main_arg()
   0.000201     [D3] main_arg: var='protocol' val='q1'
   0.000203     [D5] do_upsconf_args: not a main_arg()
   0.000205     [D5] do_upsconf_args: process as value
   0.000207     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000209     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'q1', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000212     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000217     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000221     [D5] do_upsconf_args: confupsname=ups, var=port, val=auto
   0.000223     [D5] do_upsconf_args: call main_arg()
   0.000226     [D3] main_arg: var='port' val='auto'
   0.000228     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000230     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000233     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000245     [D5] do_upsconf_args: confupsname=ups, var=vendorid, val=0001
   0.000247     [D5] do_upsconf_args: call main_arg()
   0.000250     [D3] main_arg: var='vendorid' val='0001'
   0.000252     [D5] do_upsconf_args: not a main_arg()
   0.000255     [D5] do_upsconf_args: process as value
   0.000257     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000260     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000262     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000266     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000270     [D5] do_upsconf_args: confupsname=ups, var=productid, val=0000
   0.000272     [D5] do_upsconf_args: call main_arg()
   0.000274     [D3] main_arg: var='productid' val='0000'
   0.000277     [D5] do_upsconf_args: not a main_arg()
   0.000278     [D5] do_upsconf_args: process as value
   0.000280     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000284     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000286     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000290     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000294     [D5] do_upsconf_args: confupsname=ups, var=product, val=MEC0003
   0.000296     [D5] do_upsconf_args: call main_arg()
   0.000299     [D3] main_arg: var='product' val='MEC0003'
   0.000301     [D5] do_upsconf_args: not a main_arg()
   0.000303     [D5] do_upsconf_args: process as value
   0.000305     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000307     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000312     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000314     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000318     [D5] do_upsconf_args: confupsname=ups, var=vendor, val=MEC
   0.000320     [D5] do_upsconf_args: call main_arg()
   0.000323     [D3] main_arg: var='vendor' val='MEC'
   0.000325     [D5] do_upsconf_args: not a main_arg()
   0.000327     [D5] do_upsconf_args: process as value
   0.000330     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000333     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000335     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000346     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000349     [D5] do_upsconf_args: confupsname=ups, var=bus, val=003
   0.000351     [D5] do_upsconf_args: call main_arg()
   0.000355     [D3] main_arg: var='bus' val='003'
   0.000357     [D5] do_upsconf_args: not a main_arg()
   0.000359     [D5] do_upsconf_args: process as value
   0.000361     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000365     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000367     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000370     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000378     [D3] main_arg: var='protocol' val='q1'
   0.000381     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000389     [D1] debug level is '6'
   0.000392     [D5] send_to_all: SETINFO driver.debug "6"
   0.000395     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.000956     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.000967     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.000970     [D1] Signalling UPS [ups]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.000974     Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory
   0.000976     [D1] Request for other driver to exit returned code -1
   0.000978     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.000986     [D5] send_to_all: SETINFO device.type "ups"
   0.000989     [D5] send_to_all: SETINFO driver.state "init.device"
   0.000991     [D1] upsdrv_initups...
   0.002761     [D2] Checking device 1 of 6 (1D6B/0003)
   0.002775     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.002778     [D2] Checking device 2 of 6 (0001/0000)
   0.003709     [D2] - VendorID: 0001
   0.003714     [D2] - ProductID: 0000
   0.003717     [D2] - Manufacturer: MEC
   0.003719     [D2] - Product: MEC0003
   0.003720     [D2] - Serial Number: unknown
   0.003721     [D2] - Bus: 003
   0.003722     [D2] - Bus Port: 008
   0.003723     [D2] - Device: 006
   0.003724     [D2] - Device release number: 0100
   0.003725     [D2] Trying to match device
   0.003727     [D3] match_function_regex: matching a device...
   0.003771     [D2] Device matches
   0.003774     [D2] Reading configuration descriptor 1 of 1
   0.003780     [D3] libusb_kernel_driver_active() returned 0: Success
   0.003783     [D2] failed to claim USB device: Resource busy
   0.003787     [D2] Kernel driver already detached
   0.003790     [D2] failed to claim USB device: Resource busy
   0.003792     [D2] Kernel driver already detached
   0.003797     [D2] failed to claim USB device: Resource busy
   0.003800     [D2] Kernel driver already detached
   0.003802     [D2] failed to claim USB device: Resource busy
   0.003804     [D2] Kernel driver already detached
   0.003808     Can't claim USB device [0001:0000]@0/0/0: Entity not found
   0.003813     [D5] send_to_all: SETINFO driver.state "cleanup.exit"
root@pve:~/nut-2.8.3/nut# ./drivers/nutdrv_qx -a ups -d1 -DDDDDD -x protocol=megatec
   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000073     [D1] upsdrv_makevartable...
   0.000086     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000090     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000139     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000142     [D5] do_upsconf_args: call do_global_args()
   0.000145     [D3] do_global_args: var='maxretry' val='3'
   0.000188     [D5] do_upsconf_args: confupsname=ups, var=driver, val=nutdrv_qx
   0.000191     [D5] do_upsconf_args: call main_arg()
   0.000195     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000198     [D5] do_upsconf_args: not a main_arg()
   0.000199     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000202     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000204     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000206     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000210     [D5] do_upsconf_args: confupsname=ups, var=protocol, val=q1
   0.000211     [D5] do_upsconf_args: call main_arg()
   0.000213     [D3] main_arg: var='protocol' val='q1'
   0.000215     [D5] do_upsconf_args: not a main_arg()
   0.000217     [D5] do_upsconf_args: process as value
   0.000221     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000223     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'q1', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000225     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000228     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000232     [D5] do_upsconf_args: confupsname=ups, var=port, val=auto
   0.000234     [D5] do_upsconf_args: call main_arg()
   0.000235     [D3] main_arg: var='port' val='auto'
   0.000237     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000239     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000241     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000245     [D5] do_upsconf_args: confupsname=ups, var=vendorid, val=0001
   0.000247     [D5] do_upsconf_args: call main_arg()
   0.000248     [D3] main_arg: var='vendorid' val='0001'
   0.000250     [D5] do_upsconf_args: not a main_arg()
   0.000253     [D5] do_upsconf_args: process as value
   0.000255     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000258     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000261     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000264     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000269     [D5] do_upsconf_args: confupsname=ups, var=productid, val=0000
   0.000271     [D5] do_upsconf_args: call main_arg()
   0.000274     [D3] main_arg: var='productid' val='0000'
   0.000275     [D5] do_upsconf_args: not a main_arg()
   0.000277     [D5] do_upsconf_args: process as value
   0.000279     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000282     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000286     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000290     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000294     [D5] do_upsconf_args: confupsname=ups, var=product, val=MEC0003
   0.000296     [D5] do_upsconf_args: call main_arg()
   0.000298     [D3] main_arg: var='product' val='MEC0003'
   0.000300     [D5] do_upsconf_args: not a main_arg()
   0.000303     [D5] do_upsconf_args: process as value
   0.000305     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000307     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000318     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000322     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000326     [D5] do_upsconf_args: confupsname=ups, var=vendor, val=MEC
   0.000328     [D5] do_upsconf_args: call main_arg()
   0.000330     [D3] main_arg: var='vendor' val='MEC'
   0.000332     [D5] do_upsconf_args: not a main_arg()
   0.000333     [D5] do_upsconf_args: process as value
   0.000335     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000337     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000339     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000345     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000348     [D5] do_upsconf_args: confupsname=ups, var=bus, val=003
   0.000353     [D5] do_upsconf_args: call main_arg()
   0.000355     [D3] main_arg: var='bus' val='003'
   0.000358     [D5] do_upsconf_args: not a main_arg()
   0.000361     [D5] do_upsconf_args: process as value
   0.000364     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000369     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000372     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000375     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000383     [D3] main_arg: var='protocol' val='megatec'
   0.000387     [D5] send_to_all: SETINFO driver.parameter.protocol "megatec"
   0.000389     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000397     [D1] debug level is '6'
   0.000401     [D5] send_to_all: SETINFO driver.debug "6"
   0.000404     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.000966     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.000977     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.000981     [D1] Signalling UPS [ups]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.000986     Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory
   0.000991     [D1] Request for other driver to exit returned code -1
   0.000997     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.001002     [D5] send_to_all: SETINFO device.type "ups"
   0.001005     [D5] send_to_all: SETINFO driver.state "init.device"
   0.001008     [D1] upsdrv_initups...
   0.002838     [D2] Checking device 1 of 6 (1D6B/0003)
   0.002853     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.002855     [D2] Checking device 2 of 6 (0001/0000)
   0.003811     [D2] - VendorID: 0001
   0.003818     [D2] - ProductID: 0000
   0.003819     [D2] - Manufacturer: MEC
   0.003821     [D2] - Product: MEC0003
   0.003822     [D2] - Serial Number: unknown
   0.003823     [D2] - Bus: 003
   0.003825     [D2] - Bus Port: 008
   0.003826     [D2] - Device: 006
   0.003827     [D2] - Device release number: 0100
   0.003829     [D2] Trying to match device
   0.003830     [D3] match_function_regex: matching a device...
   0.003853     [D2] Device matches
   0.003855     [D2] Reading configuration descriptor 1 of 1
   0.003860     [D3] libusb_kernel_driver_active() returned 0: Success
   0.003864     [D2] failed to claim USB device: Resource busy
   0.003868     [D2] Kernel driver already detached
   0.003871     [D2] failed to claim USB device: Resource busy
   0.003876     [D2] Kernel driver already detached
   0.003881     [D2] failed to claim USB device: Resource busy
   0.003883     [D2] Kernel driver already detached
   0.003885     [D2] failed to claim USB device: Resource busy
   0.003888     [D2] Kernel driver already detached
   0.003893     Can't claim USB device [0001:0000]@0/0/0: Entity not found
   0.003898     [D5] send_to_all: SETINFO driver.state "cleanup.exit"
root@pve:~/nut-2.8.3/nut# ./drivers/nutdrv_qx -a ups -d1 -DDDDDD -x protocol=q1
   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000073     [D1] upsdrv_makevartable...
   0.000091     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000095     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000164     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000168     [D5] do_upsconf_args: call do_global_args()
   0.000172     [D3] do_global_args: var='maxretry' val='3'
   0.000217     [D5] do_upsconf_args: confupsname=ups, var=driver, val=nutdrv_qx
   0.000220     [D5] do_upsconf_args: call main_arg()
   0.000223     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000226     [D5] do_upsconf_args: not a main_arg()
   0.000230     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000233     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000235     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000237     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000241     [D5] do_upsconf_args: confupsname=ups, var=protocol, val=q1
   0.000243     [D5] do_upsconf_args: call main_arg()
   0.000245     [D3] main_arg: var='protocol' val='q1'
   0.000247     [D5] do_upsconf_args: not a main_arg()
   0.000249     [D5] do_upsconf_args: process as value
   0.000251     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000254     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'q1', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000257     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000262     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000266     [D5] do_upsconf_args: confupsname=ups, var=port, val=auto
   0.000270     [D5] do_upsconf_args: call main_arg()
   0.000273     [D3] main_arg: var='port' val='auto'
   0.000283     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000286     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000289     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000293     [D5] do_upsconf_args: confupsname=ups, var=vendorid, val=0001
   0.000295     [D5] do_upsconf_args: call main_arg()
   0.000297     [D3] main_arg: var='vendorid' val='0001'
   0.000299     [D5] do_upsconf_args: not a main_arg()
   0.000301     [D5] do_upsconf_args: process as value
   0.000302     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000305     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000307     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000310     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000314     [D5] do_upsconf_args: confupsname=ups, var=productid, val=0000
   0.000316     [D5] do_upsconf_args: call main_arg()
   0.000318     [D3] main_arg: var='productid' val='0000'
   0.000320     [D5] do_upsconf_args: not a main_arg()
   0.000322     [D5] do_upsconf_args: process as value
   0.000324     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000327     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000330     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000334     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000339     [D5] do_upsconf_args: confupsname=ups, var=product, val=MEC0003
   0.000344     [D5] do_upsconf_args: call main_arg()
   0.000346     [D3] main_arg: var='product' val='MEC0003'
   0.000348     [D5] do_upsconf_args: not a main_arg()
   0.000350     [D5] do_upsconf_args: process as value
   0.000353     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000356     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000359     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000362     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000368     [D5] do_upsconf_args: confupsname=ups, var=vendor, val=MEC
   0.000371     [D5] do_upsconf_args: call main_arg()
   0.000374     [D3] main_arg: var='vendor' val='MEC'
   0.000376     [D5] do_upsconf_args: not a main_arg()
   0.000379     [D5] do_upsconf_args: process as value
   0.000381     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000385     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000388     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000396     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000399     [D5] do_upsconf_args: confupsname=ups, var=bus, val=003
   0.000403     [D5] do_upsconf_args: call main_arg()
   0.000405     [D3] main_arg: var='bus' val='003'
   0.000408     [D5] do_upsconf_args: not a main_arg()
   0.000410     [D5] do_upsconf_args: process as value
   0.000413     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000424     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000428     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000432     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000442     [D3] main_arg: var='protocol' val='q1'
   0.000446     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000455     [D1] debug level is '6'
   0.000459     [D5] send_to_all: SETINFO driver.debug "6"
   0.000467     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.001164     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.001179     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.001182     [D1] Signalling UPS [ups]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.001192     Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory
   0.001194     [D1] Request for other driver to exit returned code -1
   0.001197     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.001201     [D5] send_to_all: SETINFO device.type "ups"
   0.001205     [D5] send_to_all: SETINFO driver.state "init.device"
   0.001210     [D1] upsdrv_initups...
   0.003314     [D2] Checking device 1 of 6 (1D6B/0003)
   0.003337     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.003341     [D2] Checking device 2 of 6 (0001/0000)
   0.004244     [D2] - VendorID: 0001
   0.004251     [D2] - ProductID: 0000
   0.004254     [D2] - Manufacturer: MEC
   0.004257     [D2] - Product: MEC0003
   0.004259     [D2] - Serial Number: unknown
   0.004262     [D2] - Bus: 003
   0.004264     [D2] - Bus Port: 008
   0.004271     [D2] - Device: 006
   0.004274     [D2] - Device release number: 0100
   0.004283     [D2] Trying to match device
   0.004289     [D3] match_function_regex: matching a device...
   0.004319     [D2] Device matches
   0.004323     [D2] Reading configuration descriptor 1 of 1
   0.004329     [D3] libusb_kernel_driver_active() returned 0: Success
   0.004334     [D2] failed to claim USB device: Resource busy
   0.004340     [D2] Kernel driver already detached
   0.004343     [D2] failed to claim USB device: Resource busy
   0.004346     [D2] Kernel driver already detached
   0.004348     [D2] failed to claim USB device: Resource busy
   0.004350     [D2] Kernel driver already detached
   0.004354     [D2] failed to claim USB device: Resource busy
   0.004356     [D2] Kernel driver already detached
   0.004360     Can't claim USB device [0001:0000]@0/0/0: Entity not found
   0.004366     [D5] send_to_all: SETINFO driver.state "cleanup.exit"

If I run the driver without overriding protocol in command line I get following output for megatec protocol in config:

root@pve:~/nut-2.8.3/nut# ./drivers/nutdrv_qx -a ups -d1 -DDDDDD 
   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000071     [D1] upsdrv_makevartable...
   0.000085     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000087     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000137     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000141     [D5] do_upsconf_args: call do_global_args()
   0.000144     [D3] do_global_args: var='maxretry' val='3'
   0.000188     [D5] do_upsconf_args: confupsname=ups, var=driver, val=nutdrv_qx
   0.000191     [D5] do_upsconf_args: call main_arg()
   0.000193     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000195     [D5] do_upsconf_args: not a main_arg()
   0.000196     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000198     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000200     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000201     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000206     [D5] do_upsconf_args: confupsname=ups, var=protocol, val=megatec
   0.000208     [D5] do_upsconf_args: call main_arg()
   0.000209     [D3] main_arg: var='protocol' val='megatec'
   0.000210     [D5] do_upsconf_args: not a main_arg()
   0.000212     [D5] do_upsconf_args: process as value
   0.000213     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000215     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'megatec', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000220     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000223     [D5] send_to_all: SETINFO driver.parameter.protocol "megatec"
   0.000230     [D5] do_upsconf_args: confupsname=ups, var=port, val=auto
   0.000231     [D5] do_upsconf_args: call main_arg()
   0.000233     [D3] main_arg: var='port' val='auto'
   0.000234     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000236     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000238     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000243     [D5] do_upsconf_args: confupsname=ups, var=vendorid, val=0001
   0.000244     [D5] do_upsconf_args: call main_arg()
   0.000245     [D3] main_arg: var='vendorid' val='0001'
   0.000248     [D5] do_upsconf_args: not a main_arg()
   0.000251     [D5] do_upsconf_args: process as value
   0.000253     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000256     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000259     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000261     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000266     [D5] do_upsconf_args: confupsname=ups, var=productid, val=0000
   0.000269     [D5] do_upsconf_args: call main_arg()
   0.000270     [D3] main_arg: var='productid' val='0000'
   0.000271     [D5] do_upsconf_args: not a main_arg()
   0.000273     [D5] do_upsconf_args: process as value
   0.000275     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000277     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000279     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000284     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000289     [D5] do_upsconf_args: confupsname=ups, var=product, val=MEC0003
   0.000291     [D5] do_upsconf_args: call main_arg()
   0.000293     [D3] main_arg: var='product' val='MEC0003'
   0.000294     [D5] do_upsconf_args: not a main_arg()
   0.000296     [D5] do_upsconf_args: process as value
   0.000298     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000302     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000304     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000307     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000313     [D5] do_upsconf_args: confupsname=ups, var=vendor, val=MEC
   0.000315     [D5] do_upsconf_args: call main_arg()
   0.000318     [D3] main_arg: var='vendor' val='MEC'
   0.000319     [D5] do_upsconf_args: not a main_arg()
   0.000321     [D5] do_upsconf_args: process as value
   0.000323     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000325     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000328     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000334     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000337     [D5] do_upsconf_args: confupsname=ups, var=bus, val=003
   0.000341     [D5] do_upsconf_args: call main_arg()
   0.000343     [D3] main_arg: var='bus' val='003'
   0.000345     [D5] do_upsconf_args: not a main_arg()
   0.000346     [D5] do_upsconf_args: process as value
   0.000348     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000351     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000353     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000356     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000363     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000373     [D1] debug level is '6'
   0.000376     [D5] send_to_all: SETINFO driver.debug "6"
   0.000379     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.000927     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.000938     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.000941     [D1] Signalling UPS [ups]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.000949     Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory
   0.000960     [D1] Request for other driver to exit returned code -1
   0.000964     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.000967     [D5] send_to_all: SETINFO device.type "ups"
   0.000971     [D5] send_to_all: SETINFO driver.state "init.device"
   0.000973     [D1] upsdrv_initups...
   0.002850     [D2] Checking device 1 of 6 (1D6B/0003)
   0.002865     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.002868     [D2] Checking device 2 of 6 (0001/0000)
   0.003736     [D2] - VendorID: 0001
   0.003740     [D2] - ProductID: 0000
   0.003742     [D2] - Manufacturer: MEC
   0.003743     [D2] - Product: MEC0003
   0.003745     [D2] - Serial Number: unknown
   0.003746     [D2] - Bus: 003
   0.003748     [D2] - Bus Port: 008
   0.003749     [D2] - Device: 006
   0.003751     [D2] - Device release number: 0100
   0.003753     [D2] Trying to match device
   0.003754     [D3] match_function_regex: matching a device...
   0.003777     [D2] Device matches
   0.003780     [D2] Reading configuration descriptor 1 of 1
   0.003786     [D3] libusb_kernel_driver_active() returned 0: Success
   0.003799     [D2] Claimed interface 0 successfully
   0.003803     [D3] nut_libusb_set_altinterface: skipped libusb_set_interface_alt_setting(udev, 0, 0)
   0.003809     [D5] send_to_all: SETINFO ups.vendorid "0001"
   0.003813     [D5] send_to_all: SETINFO ups.productid "0000"
   0.003816     [D2] Skipping protocol Voltronic 0.11
   0.003823     [D2] Skipping protocol Voltronic-QS 0.10
   0.003825     [D2] Skipping protocol Voltronic-QS-Hex 0.11
   0.003827     [D2] Skipping protocol Mustek 0.08
   0.003829     [D2] Skipping protocol Megatec/old 0.08
   0.003831     [D2] Skipping protocol BestUPS 0.08
   0.003832     [D2] Skipping protocol Mecer 0.09
   0.003835     [D3] send: Q1
   0.003839     [D4] command index: 0x03
   0.226122     [D5] read: (47 bytes) => 28 32 34 34 2e 38 20 32 34 36 2e 30 20 32 34 34 2e 35
   0.226134     [D5]  20 30 31 31 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.226136     [D5]  30 30 31 0d
   0.226138     [D3] read: (244.8 246.0 244.5 011 50.1 13.7 --.- 00001001
   0.226145     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.226147     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.226174     [D5] send_to_all: SETINFO input.voltage "244.8"
   0.226178     [D3] send: I
   0.226179     [D4] command index: 0x0c
   0.410802     [D5] read: (39 bytes) => 23 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20
   0.410814     [D5]  20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 0d
   0.410817     [D3] read: #                                     
   0.410824     [D1] ups_infoval_set: non significant value [ups.firmware]
   0.410827     [D5] send_to_all: DELINFO input.voltage
   0.410829     [D3] send: Q1
   0.410830     [D4] command index: 0x03
   0.633032     [D5] read: (47 bytes) => 28 32 34 34 2e 35 20 32 34 36 2e 30 20 32 34 34 2e 36
   0.633045     [D5]  20 30 31 32 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   0.633047     [D5]  30 30 31 0d
   0.633050     [D3] read: (244.5 246.0 244.6 012 50.1 13.7 --.- 00001001
   0.633055     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   0.633057     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   0.633086     [D5] send_to_all: SETINFO input.voltage "244.5"
   0.633090     [D3] send: I
   0.633092     [D4] command index: 0x0c
   0.817944     [D5] read: (39 bytes) => 23 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20
   0.817957     [D5]  20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 0d
   0.817959     [D3] read: #                                     
   0.817963     [D1] ups_infoval_set: non significant value [ups.firmware]
   0.817966     [D5] send_to_all: DELINFO input.voltage
   0.817968     [D3] send: Q1
   0.817970     [D4] command index: 0x03
   1.040317     [D5] read: (47 bytes) => 28 32 34 34 2e 35 20 32 34 36 2e 30 20 32 34 34 2e 35
   1.040330     [D5]  20 30 31 33 20 35 30 2e 31 20 31 33 2e 37 20 2d 2d 2e 2d 20 30 30 30 30 31
   1.040332     [D5]  30 30 31 0d
   1.040334     [D3] read: (244.5 246.0 244.5 013 50.1 13.7 --.- 00001001
   1.040340     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '.'
   1.040341     [D1] minimize_formatting_string: in-escape: unexpected formatting char: '1'
   1.040371     [D5] send_to_all: SETINFO input.voltage "244.5"
   1.040376     [D3] send: I
   1.040377     [D4] command index: 0x0c
   1.224900     [D5] read: (39 bytes) => 23 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20
   1.224912     [D5]  20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 0d
   1.224915     [D3] read: #                                     
   1.224919     [D1] ups_infoval_set: non significant value [ups.firmware]
   1.224921     [D5] send_to_all: DELINFO input.voltage
   1.224924     [D2] Skipping protocol Zinto 0.07
   1.224925     [D2] Skipping protocol Masterguard 0.03
   1.224927     [D2] Skipping protocol Hunnox 0.02
   1.224928     [D2] Skipping protocol Ablerex 0.02
   1.224929     [D2] Skipping protocol INNOVART31 0.02
   1.224954     [D2] Skipping protocol INNOVART33 0.01
   1.224955     [D2] Skipping protocol Q2 0.01
   1.224957     [D2] Skipping protocol Q6 0.01
   1.224958     [D2] Skipping protocol Gtec 0.01
   1.224959     [D2] Skipping protocol Q1 0.08
   1.224961     Device not supported!
   1.224962     Device not supported!
   1.224969     [D5] send_to_all: SETINFO driver.state "cleanup.exit"

And following for q1 in config:

root@pve:~/nut-2.8.3/nut# ./drivers/nutdrv_qx -a ups -d1 -DDDDDD 
   0.000000     [D5] send_to_all: SETINFO driver.state "init.starting"
Network UPS Tools 2.8.3.285-285+g6393115cb (development iteration after 2.8.3) - Generic Q* USB/Serial driver 0.43
USB communication driver (libusb 1.0) 0.50
   0.000056     [D1] upsdrv_makevartable...
   0.000075     [D5] send_to_all: SETINFO driver.version.usb "libusb-1.0.26 (API: 0x01000109)"
   0.000078     [D1] Using USB implementation: libusb-1.0.26 (API: 0x01000109)
   0.000132     [D5] do_upsconf_args: confupsname=(null), var=maxretry, val=3
   0.000135     [D5] do_upsconf_args: call do_global_args()
   0.000139     [D3] do_global_args: var='maxretry' val='3'
   0.000180     [D5] do_upsconf_args: confupsname=ups, var=driver, val=nutdrv_qx
   0.000183     [D5] do_upsconf_args: call main_arg()
   0.000186     [D3] main_arg: var='driver' val='nutdrv_qx'
   0.000188     [D5] do_upsconf_args: not a main_arg()
   0.000190     [D5] do_upsconf_args: this is a 'driver' setting, may we proceed?
   0.000193     [D6] testval_reloadable: var=driver, oldval=nutdrv_qx, newval=nutdrv_qx, reloadable=0, reload_flag=0
   0.000195     [D6] testval_reloadable: verdict for (re)loading var=driver value: -1
   0.000197     [D5] do_upsconf_args: 'driver' setting already applied with this value
   0.000202     [D5] do_upsconf_args: confupsname=ups, var=protocol, val=q1
   0.000203     [D5] do_upsconf_args: call main_arg()
   0.000206     [D3] main_arg: var='protocol' val='q1'
   0.000208     [D5] do_upsconf_args: not a main_arg()
   0.000210     [D5] do_upsconf_args: process as value
   0.000213     [D6] testvar_reloadable: searching for var=protocol, vartype=2, reload_flag=0
   0.000216     [D6] testvar_reloadable: found var=protocol, val='(null)' => 'q1', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000218     [D6] testvar_reloadable: verdict for (re)loading var=protocol value: 1
   0.000223     [D5] send_to_all: SETINFO driver.parameter.protocol "q1"
   0.000228     [D5] do_upsconf_args: confupsname=ups, var=port, val=auto
   0.000230     [D5] do_upsconf_args: call main_arg()
   0.000233     [D3] main_arg: var='port' val='auto'
   0.000235     [D6] testinfo_reloadable: var=port, infoname=driver.parameter.port, newval=auto, reloadable=0, reload_flag=0
   0.000238     [D6] testinfo_reloadable: verdict for (re)loading var=port value: 1
   0.000241     [D5] send_to_all: SETINFO driver.parameter.port "auto"
   0.000245     [D5] do_upsconf_args: confupsname=ups, var=vendorid, val=0001
   0.000247     [D5] do_upsconf_args: call main_arg()
   0.000249     [D3] main_arg: var='vendorid' val='0001'
   0.000252     [D5] do_upsconf_args: not a main_arg()
   0.000254     [D5] do_upsconf_args: process as value
   0.000256     [D6] testvar_reloadable: searching for var=vendorid, vartype=2, reload_flag=0
   0.000259     [D6] testvar_reloadable: found var=vendorid, val='(null)' => '0001', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000261     [D6] testvar_reloadable: verdict for (re)loading var=vendorid value: 1
   0.000264     [D5] send_to_all: SETINFO driver.parameter.vendorid "0001"
   0.000269     [D5] do_upsconf_args: confupsname=ups, var=productid, val=0000
   0.000271     [D5] do_upsconf_args: call main_arg()
   0.000274     [D3] main_arg: var='productid' val='0000'
   0.000276     [D5] do_upsconf_args: not a main_arg()
   0.000278     [D5] do_upsconf_args: process as value
   0.000280     [D6] testvar_reloadable: searching for var=productid, vartype=2, reload_flag=0
   0.000283     [D6] testvar_reloadable: found var=productid, val='(null)' => '0000', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000286     [D6] testvar_reloadable: verdict for (re)loading var=productid value: 1
   0.000289     [D5] send_to_all: SETINFO driver.parameter.productid "0000"
   0.000293     [D5] do_upsconf_args: confupsname=ups, var=product, val=MEC0003
   0.000296     [D5] do_upsconf_args: call main_arg()
   0.000298     [D3] main_arg: var='product' val='MEC0003'
   0.000300     [D5] do_upsconf_args: not a main_arg()
   0.000303     [D5] do_upsconf_args: process as value
   0.000305     [D6] testvar_reloadable: searching for var=product, vartype=2, reload_flag=0
   0.000308     [D6] testvar_reloadable: found var=product, val='(null)' => 'MEC0003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000310     [D6] testvar_reloadable: verdict for (re)loading var=product value: 1
   0.000314     [D5] send_to_all: SETINFO driver.parameter.product "MEC0003"
   0.000318     [D5] do_upsconf_args: confupsname=ups, var=vendor, val=MEC
   0.000320     [D5] do_upsconf_args: call main_arg()
   0.000323     [D3] main_arg: var='vendor' val='MEC'
   0.000325     [D5] do_upsconf_args: not a main_arg()
   0.000327     [D5] do_upsconf_args: process as value
   0.000329     [D6] testvar_reloadable: searching for var=vendor, vartype=2, reload_flag=0
   0.000332     [D6] testvar_reloadable: found var=vendor, val='(null)' => 'MEC', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000335     [D6] testvar_reloadable: verdict for (re)loading var=vendor value: 1
   0.000341     [D5] send_to_all: SETINFO driver.parameter.vendor "MEC"
   0.000344     [D5] do_upsconf_args: confupsname=ups, var=bus, val=003
   0.000347     [D5] do_upsconf_args: call main_arg()
   0.000349     [D3] main_arg: var='bus' val='003'
   0.000351     [D5] do_upsconf_args: not a main_arg()
   0.000354     [D5] do_upsconf_args: process as value
   0.000356     [D6] testvar_reloadable: searching for var=bus, vartype=2, reload_flag=0
   0.000359     [D6] testvar_reloadable: found var=bus, val='(null)' => '003', vartype=2 => 2, found=0, reloadable=0, reload_flag=0
   0.000361     [D6] testvar_reloadable: verdict for (re)loading var=bus value: 1
   0.000365     [D5] send_to_all: SETINFO driver.parameter.bus "003"
   0.000371     [D1] Network UPS Tools version 2.8.3.285-285+g6393115cb (development iteration after 2.8.3), 64-bit build for x86_64, built with gcc (Debian 12.2.0-14) 12.2.0 and configured with flags: --prefix=/usr --sbindir=/usr/sbin --bindir=/usr/bin --with-drvpath=/usr/lib/nut --datadir=/usr/share/nut --sysconfdir=/etc/nut --libdir=/lib/x86_64-linux-gnu --with-group=nut --with-user=nut --enable-Wcolor --enable-configure-debug --enable-warnings --enable-Werror --enable-keep_nut_report_feature --with-all=auto --with-cgi=auto --with-serial=auto --with-dev=auto --with-nut_monitor=auto --with-pynut=auto --disable-force-nut-version-header --enable-check-NIT --enable-maintainer-mode --with-doc=skip PKG_CONFIG_PATH=/usr/local/lib/x86_64-linux-gnu/pkgconfig:/usr/local/lib/pkgconfig:/usr/local/share/pkgconfig:/usr/lib/x86_64-linux-gnu/pkgconfig:/usr/lib/pkgconfig:/usr/share/pkgconfig --with-unmapped-data-points --with-debuginfo=auto
   0.000379     [D1] debug level is '6'
   0.000382     [D5] send_to_all: SETINFO driver.debug "6"
   0.000385     [D5] send_to_all: SETFLAGS driver.debug RW NUMBER
   0.000978     [D1] Succeeded to become_user(nut): now UID=110 GID=117
   0.000995     Can't chdir to /var/state/ups (but we do not require that to dump data): No such file or directory
   0.000998     [D1] Signalling UPS [ups]: driver.exit (quietly, no fuss if no driver is running or responding)
   0.001002     Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory
   0.001005     [D1] Request for other driver to exit returned code -1
   0.001007     [D1] Socket dialog with the other driver instance (may be absent) failed: No such file or directory
   0.001010     [D5] send_to_all: SETINFO device.type "ups"
   0.001013     [D5] send_to_all: SETINFO driver.state "init.device"
   0.001015     [D1] upsdrv_initups...
   0.002830     [D2] Checking device 1 of 6 (1D6B/0003)
   0.002844     [D1] Failed to open device (1D6B/0003), skipping: Access denied (insufficient permissions)
   0.002847     [D2] Checking device 2 of 6 (0001/0000)
   0.003766     [D2] - VendorID: 0001
   0.003770     [D2] - ProductID: 0000
   0.003772     [D2] - Manufacturer: MEC
   0.003773     [D2] - Product: MEC0003
   0.003774     [D2] - Serial Number: unknown
   0.003775     [D2] - Bus: 003
   0.003777     [D2] - Bus Port: 008
   0.003778     [D2] - Device: 006
   0.003779     [D2] - Device release number: 0100
   0.003780     [D2] Trying to match device
   0.003782     [D3] match_function_regex: matching a device...
   0.003805     [D2] Device matches
   0.003807     [D2] Reading configuration descriptor 1 of 1
   0.003812     [D3] libusb_kernel_driver_active() returned 0: Success
   0.003815     [D2] failed to claim USB device: Resource busy
   0.003822     [D2] Kernel driver already detached
   0.003824     [D2] failed to claim USB device: Resource busy
   0.003826     [D2] Kernel driver already detached
   0.003827     [D2] failed to claim USB device: Resource busy
   0.003829     [D2] Kernel driver already detached
   0.003831     [D2] failed to claim USB device: Resource busy
   0.003836     [D2] Kernel driver already detached
   0.003839     Can't claim USB device [0001:0000]@0/0/0: Entity not found
   0.003844     [D5] send_to_all: SETINFO driver.state "cleanup.exit"

[jimklimov]

Cheers, one thing that looks concerning here is Can't chdir to /var/state/ups - did you create and chown it properly?

My current theory, based on failed to claim USB device: Resource busy in later attempts, is that once you've saved the working protocol=q1 into ups.conf ("HOWEVER when I change protocol in config file to q1 I get the following error..."), then NDE https://github.com/networkupstools/nut/wiki/nut%E2%80%90driver%E2%80%90enumerator-(NDE) kicks in to finally generate a successfully working unit named like nut-driver@ups.service which grabs the libusb device and so your subsequent runs can not. And due to lack of location for socket/PID files of the driver, this can not be detected either (Can't open /var/state/ups/nutdrv_qx-ups: No such file or directory).

Although I'd expect the service-wrapped driver to fail and die when it can't create the socket, so maybe thanks to systemd you have a looping attempt to spawn the driver, it dies, systemd tries again - so much of the time the USB device is busy with a new short-lived driver instance?