search

pwr-Solaar / Solaar

Linux device manager for Logitech devices
https://pwr-solaar.github.io/Solaar
GNU General Public License v2.0
5.4k stars 401 forks source link

"Exception: pairing failed: failed" when pairing logitech mx master 3s #2534

Open jim4067 opened 3 months ago

jim4067 commented 3 months ago

Information

``` solaar version 1.1.13 Bolt Receiver Device path : /dev/hidraw3 USB id : 046d:C548 Serial : 36463033424332363535313036443435 Has 0 paired device(s) out of a maximum of 6. Notifications: wireless, software present (0x000900) Device activity counters: (empty) ```

Describe the bug Getting the error above when trying to pair MX Master 3s mouse using the bolt receiver

To Reproduce Steps to reproduce the behavior:

  1. Install solaar from git instruction, from tag 1.1.13
  2. Try pairing new device(in my case mx master 3s)
  3. After pressing click combinations it errors our with error shown above.
  4. See error

Screenshots If applicable, add screenshots to help explain your problem. image

Additional context Add any other context about the problem here.

pfps commented 3 months ago

I have been able to successfully pair. The double press is rather finicky, if I remember correctly.

If you still can't pair, run Solaar with -ddd flag and post output

jim4067 commented 3 months ago

Yes, It always fails at the last step. I've tried my hardest to be as precise as possible during the double press but nothing seems to work.

Here's the verbose output 

2024-06-24 12:31:35,858,858    DEBUG [MainThread] hidapi.udev: Starting dbus enumeration
2024-06-24 12:31:35,859,859    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.3/usb1/1-2/1-2:1.0/0003:0D62:06EB.0006/hidraw/hidraw5')
2024-06-24 12:31:35,966,966     INFO [MainThread] hidapi.udev: Report Descriptor not processed for DEVICE /dev/hidraw5 BID 0003 VID 00000D62 PID 000006EB: Expecting 9 usages but got 257
2024-06-24 12:31:35,966,966    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.3/usb1/1-2/1-2:1.1/0003:0D62:06EB.0007/hidraw/hidraw6')
2024-06-24 12:31:35,967,967    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.3/usb1/1-2/1-2:1.2/0003:0D62:06EB.0008/hidraw/hidraw7')
2024-06-24 12:31:35,968,968    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.0/0003:046D:C548.0002/hidraw/hidraw1')
2024-06-24 12:31:35,969,969    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.1/0003:046D:C548.0003/hidraw/hidraw2')
2024-06-24 12:31:35,971,971    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.2/0003:046D:C548.0004/hidraw/hidraw3')
2024-06-24 12:31:35,972,972     INFO [MainThread] hidapi.udev: Found device /dev/hidraw3 BID 0003 VID 0000046D PID 0000C548 HID++ True True USB 2 2
2024-06-24 12:31:35,972,972     INFO [MainThread] hidapi.udev: OPEN PATH /dev/hidraw3
2024-06-24 12:31:35,972,972     INFO [MainThread] logitech_receiver.base: New lock 13
2024-06-24 12:31:35,972,972    DEBUG [MainThread] logitech_receiver.base: (13) <= w[10 FF 83FB 000000]
2024-06-24 12:31:35,974,974    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 83FB 36463033424332363535313036443435]
2024-06-24 12:31:35,974,974    DEBUG [MainThread] logitech_receiver.base: (13) <= w[10 FF 8003 FF0000]
2024-06-24 12:31:35,977,977    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 8F80 030200]
2024-06-24 12:31:35,977,977    DEBUG [MainThread] logitech_receiver.base: (13) device 0xFF error on request {8003}: 2 = invalid address
2024-06-24 12:31:35,977,977    DEBUG [MainThread] solaar.cli: [/dev/hidraw3] => <BoltReceiver(/dev/hidraw3,13)>
2024-06-24 12:31:35,977,977    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.3/0003:046D:C548.0005/hidraw/hidraw4')
2024-06-24 12:31:35,979,979    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/platform/AMDI0010:03/i2c-0/i2c-QXTP7868:00/0018:27C6:01E8.0001/hidraw/hidraw0')
2024-06-24 12:31:35,983,983     INFO [MainThread] hidapi.udev: Report Descriptor not processed for DEVICE /dev/hidraw0 BID 0018 VID 000027C6 PID 000001E8: Expecting 64 usages but got 257
2024-06-24 12:31:35,983,983    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/virtual/misc/uhid/0005:0000:0000.0017/hidraw/hidraw8')
2024-06-24 12:31:35,984,984     INFO [MainThread] hidapi.udev: Report Descriptor not processed for DEVICE /dev/hidraw8 BID 0005 VID 00000000 PID 00000000: Expecting 120 usages but got 121
2024-06-24 12:31:35,984,984    DEBUG [MainThread] logitech_receiver.base: (13) <= w[10 FF 8100 000000]
2024-06-24 12:31:35,987,987    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 8100 000900]
2024-06-24 12:31:35,987,987    DEBUG [MainThread] logitech_receiver.base: (13) <= w[10 FF 8102 000000]
2024-06-24 12:31:35,990,990    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 8102 000000]
2024-06-24 12:31:35,990,990    DEBUG [MainThread] logitech_receiver.base: (13) <= w[10 FF 80C0 1E0100]
2024-06-24 12:31:35,993,993    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 5300 000000]
2024-06-24 12:31:35,993,993     INFO [MainThread] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,13)>: discovery lock is open
2024-06-24 12:31:35,994,994    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 80C0 000000]
Bolt Pairing: long-press the pairing key or button on your device (timing out in 30 seconds).
2024-06-24 12:31:38,579,579    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4F00 0000100234B0983788BE3CD3010102C3]
2024-06-24 12:31:38,580,580    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4F00 00010C4D58204D617374657220335300]
Bolt Pairing: discovered MX Master 3S
2024-06-24 12:31:38,580,580    DEBUG [MainThread] logitech_receiver.base: (13) <= w[11 FF 82C1 0100983788BE3CD3020A000000000000]
2024-06-24 12:31:38,582,582    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 82C1 000000]
2024-06-24 12:31:38,583,583    DEBUG [MainThread] logitech_receiver.base: (13) => r[10 FF 5302 000000]
2024-06-24 12:31:38,583,583     INFO [MainThread] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,13)>: discovery lock is closed
2024-06-24 12:31:38,584,584    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 5400 00983788BE3CD3000000000000000000]
2024-06-24 12:31:38,584,584     INFO [MainThread] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,13)>: pairing lock is open
2024-06-24 12:31:40,678,678    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4D06 303030383938983788BE3CD300000000]
Bolt Pairing: press right, right, right, left, left, left, left, left, right, left
and then press left and right buttons simultaneously
2024-06-24 12:31:40,678,678    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E00 983788BE3CD300000000000000000000]
2024-06-24 12:31:45,863,863    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:46,215,215    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:46,724,724    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:47,707,707    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:48,413,413    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:49,193,193    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:49,890,890    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:50,842,842    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:51,915,915    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:52,545,545    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:31:56,775,775    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 4E04 983788BE3CD300000000000000000000]
2024-06-24 12:31:56,798,798    DEBUG [MainThread] logitech_receiver.base: (13) => r[11 FF 5402 02983788BE3CD3000000000000000000]
2024-06-24 12:31:56,798,798     INFO [MainThread] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,13)>: pairing lock is closed
2024-06-24 12:31:56,799,799  WARNING [MainThread] logitech_receiver.notifications: pairing error 2: failed
solaar: error: Traceback (most recent call last):
  File "/home/jimii/Solaar/lib/solaar/cli/__init__.py", line 221, in run
    m.run(c, args, _find_receiver, _find_device)
  File "/home/jimii/Solaar/lib/solaar/cli/pair.py", line 134, in run
    raise Exception(f"pairing failed: {error}")
Exception: pairing failed: failed
pfps commented 3 months ago

Did you try pairing from the GUI? Run Solaar as solaar -ddd, clidk on the Bolt receiver, and then on the Pair button.

I won't have access to a Bolt mouse for about a month so I can't test pairing out myself until then.

jim4067 commented 3 months ago

No worries. I'll try to pair via windows.

It seems to fail for GUI as well.

image

Here are the debug logs

2024-06-24 12:40:00,887,887     INFO [MainThread] solaar.gtk: version 1.1.13, language en_GB (UTF-8)
2024-06-24 12:40:00,889,889    DEBUG [MainThread] hidapi.udev: Starting dbus monitoring
2024-06-24 12:40:00,889,889     INFO [MainThread] solaar.dbus: connected to system dbus, watching for suspend/resume events
2024-06-24 12:40:00,891,891    DEBUG [MainThread] solaar.ui: startup registered=True, remote=False
2024-06-24 12:40:00,891,891    DEBUG [AsyncUI] solaar.tasks: started
2024-06-24 12:40:00,891,891     INFO [MainThread] solaar.ui.notify: starting desktop notifications
2024-06-24 12:40:00,893,893    DEBUG [MainThread] solaar.ui.icons: icon theme paths: ['/home/jimii/.local/share/icons', '/home/jimii/.icons', '/usr/share/pop/icons', '/usr/share/gnome/icons', '/home/jimii/.local/share/flatpak/exports/share/icons', '/var/lib/flatpak/exports/share/icons', '/usr/local/share/icons', '/usr/share/icons', '/usr/share/pop/pixmaps', '/usr/share/gnome/pixmaps', '/home/jimii/.local/share/flatpak/exports/share/pixmaps', '/var/lib/flatpak/exports/share/pixmaps', '/usr/local/share/pixmaps', '/usr/share/pixmaps']
2024-06-24 12:40:00,939,939     INFO [MainThread] solaar.listener: starting receiver listening threads
2024-06-24 12:40:00,940,940    DEBUG [MainThread] hidapi.udev: Starting dbus enumeration
2024-06-24 12:40:00,941,941    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.3/usb1/1-2/1-2:1.0/0003:0D62:06EB.0006/hidraw/hidraw5')
2024-06-24 12:40:01,064,064     INFO [MainThread] hidapi.udev: Report Descriptor not processed for DEVICE /dev/hidraw5 BID 0003 VID 00000D62 PID 000006EB: Expecting 9 usages but got 257
2024-06-24 12:40:01,065,065    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.3/usb1/1-2/1-2:1.1/0003:0D62:06EB.0007/hidraw/hidraw6')
2024-06-24 12:40:01,066,066    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.3/usb1/1-2/1-2:1.2/0003:0D62:06EB.0008/hidraw/hidraw7')
2024-06-24 12:40:01,067,067    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.0/0003:046D:C548.0002/hidraw/hidraw1')
2024-06-24 12:40:01,068,068    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.1/0003:046D:C548.0003/hidraw/hidraw2')
2024-06-24 12:40:01,070,070    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.2/0003:046D:C548.0004/hidraw/hidraw3')
2024-06-24 12:40:01,070,070     INFO [MainThread] hidapi.udev: Found device /dev/hidraw3 BID 0003 VID 0000046D PID 0000C548 HID++ True True USB 2 2
2024-06-24 12:40:01,070,070     INFO [MainThread] solaar.listener: receiver event add DeviceInfo(path='/dev/hidraw3', bus_id=3, vendor_id='046D', product_id='C548', interface=2, driver='hid-generic', manufacturer=None, product=None, serial='', release=None, isDevice=None, hidpp_short=True, hidpp_long=True)
2024-06-24 12:40:01,071,071     INFO [MainThread] hidapi.udev: OPEN PATH /dev/hidraw3
2024-06-24 12:40:01,071,071     INFO [MainThread] logitech_receiver.base: New lock 14
2024-06-24 12:40:01,071,071    DEBUG [MainThread] logitech_receiver.base: (14) <= w[10 FF 83FB 000000]
2024-06-24 12:40:01,072,072    DEBUG [MainThread] logitech_receiver.base: (14) => r[11 FF 83FB 36463033424332363535313036443435]
2024-06-24 12:40:01,073,073    DEBUG [MainThread] logitech_receiver.base: (14) <= w[10 FF 8003 FF0000]
2024-06-24 12:40:01,075,075    DEBUG [MainThread] logitech_receiver.base: (14) => r[10 FF 8F80 030200]
2024-06-24 12:40:01,076,076    DEBUG [MainThread] logitech_receiver.base: (14) device 0xFF error on request {8003}: 2 = invalid address
2024-06-24 12:40:01,076,076     INFO [SolaarListener:hidraw3] logitech_receiver.listener: started with <BoltReceiver(/dev/hidraw3,T14)> (14)
2024-06-24 12:40:01,076,076     INFO [SolaarListener:hidraw3] solaar.listener: <BoltReceiver(/dev/hidraw3,T14)>: notifications listener has started (14)
2024-06-24 12:40:01,076,076     INFO [SolaarListener:hidraw3] logitech_receiver.base: New lock <_ThreadedHandle(/dev/hidraw3)>
2024-06-24 12:40:01,076,076    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) <= w[10 FF 8000 000900]
2024-06-24 12:40:01,076,076    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/pci0000:00/0000:00:08.1/0000:03:00.4/usb3/3-2/3-2:1.3/0003:046D:C548.0005/hidraw/hidraw4')
2024-06-24 12:40:01,078,078    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/platform/AMDI0010:03/i2c-0/i2c-QXTP7868:00/0018:27C6:01E8.0001/hidraw/hidraw0')
2024-06-24 12:40:01,082,082     INFO [MainThread] hidapi.udev: Report Descriptor not processed for DEVICE /dev/hidraw0 BID 0018 VID 000027C6 PID 000001E8: Expecting 64 usages but got 257
2024-06-24 12:40:01,082,082    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 8000 000000]
2024-06-24 12:40:01,082,082    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) <= w[10 FF 8100 000000]
2024-06-24 12:40:01,082,082    DEBUG [MainThread] hidapi.udev: Dbus event add Device('/sys/devices/virtual/misc/uhid/0005:0000:0000.0017/hidraw/hidraw8')
2024-06-24 12:40:01,083,083     INFO [MainThread] hidapi.udev: Report Descriptor not processed for DEVICE /dev/hidraw8 BID 0005 VID 00000000 PID 00000000: Expecting 120 usages but got 121
2024-06-24 12:40:01,083,083    DEBUG [MainThread] solaar.ui: activate
2024-06-24 12:40:01,084,084    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 8100 000900]
2024-06-24 12:40:01,085,085     INFO [SolaarListener:hidraw3] logitech_receiver.receiver: <BoltReceiver(/dev/hidraw3,T14)>: receiver notifications enabled => ('wireless', 'software present')
2024-06-24 12:40:01,085,085    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) <= w[10 FF 8002 020000]
2024-06-24 12:40:01,087,087    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 8002 000000]
2024-06-24 12:40:01,088,088     INFO [SolaarListener:hidraw3] solaar.listener: status_changed <BoltReceiver(/dev/hidraw3,T14)>: present (0) 
2024-06-24 12:40:01,088,088     INFO [MainThread] hidapi.udev: OPEN PATH /dev/hidraw3
2024-06-24 12:40:01,088,088    DEBUG [MainThread] solaar.ui: status changed: <BoltReceiver(/dev/hidraw3,T17)> (None) None
2024-06-24 12:40:01,088,088    DEBUG [MainThread] solaar.ui.window: new receiver row ('/dev/hidraw3', 0, True, 'Bolt Receiver', 'preferences-desktop-peripherals', None, None, <BoltReceiver(/dev/hidraw3,T17)>)
2024-06-24 12:40:11,980,980    DEBUG [MainThread] logitech_receiver.base: (17) <= w[10 FF 8102 000000]
2024-06-24 12:40:11,982,982    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 8102 000000]
2024-06-24 12:40:11,982,982    DEBUG [MainThread] logitech_receiver.base: (17) => r[10 FF 8102 000000]
2024-06-24 12:40:15,101,101    DEBUG [MainThread] logitech_receiver.base: (17) <= w[10 FF 80C0 1E0100]
2024-06-24 12:40:15,102,102    DEBUG [MainThread] logitech_receiver.base: (17) => r[10 FF 5300 000000]
2024-06-24 12:40:15,102,102    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 5300 000000]
2024-06-24 12:40:15,103,103     INFO [SolaarListener:hidraw3] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,T14)>: discovery lock is open
2024-06-24 12:40:15,103,103    DEBUG [MainThread] logitech_receiver.base: (17) => r[10 FF 80C0 000000]
2024-06-24 12:40:15,103,103     INFO [SolaarListener:hidraw3] solaar.listener: status_changed <BoltReceiver(/dev/hidraw3,T14)>: present (1) discovery lock is open
2024-06-24 12:40:15,106,106    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 80C0 000000]
2024-06-24 12:40:15,132,132    DEBUG [MainThread] solaar.ui: status changed: <BoltReceiver(/dev/hidraw3,T17)> (NOTIFICATION) discovery lock is open
2024-06-24 12:40:24,455,455    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4F00 0000100234B0983788BE3CD3010102C2]
2024-06-24 12:40:24,456,456    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4F00 00010C4D58204D617374657220335300]
2024-06-24 12:40:24,617,617    DEBUG [MainThread] logitech_receiver.base: (17) <= w[11 FF 82C1 0100983788BE3CD3020A000000000000]
2024-06-24 12:40:24,619,619    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 82C1 000000]
2024-06-24 12:40:24,619,619    DEBUG [MainThread] logitech_receiver.base: (17) => r[10 FF 82C1 000000]
2024-06-24 12:40:24,620,620    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[10 FF 5302 000000]
2024-06-24 12:40:24,620,620     INFO [SolaarListener:hidraw3] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,T14)>: discovery lock is closed
2024-06-24 12:40:24,620,620     INFO [SolaarListener:hidraw3] solaar.listener: status_changed <BoltReceiver(/dev/hidraw3,T14)>: present (1) discovery lock is closed
2024-06-24 12:40:24,621,621    DEBUG [MainThread] solaar.ui: status changed: <BoltReceiver(/dev/hidraw3,T17)> (NOTIFICATION) discovery lock is closed
2024-06-24 12:40:24,621,621    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 5400 00983788BE3CD3000000000000000000]
2024-06-24 12:40:24,621,621     INFO [SolaarListener:hidraw3] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,T14)>: pairing lock is open
2024-06-24 12:40:24,621,621     INFO [SolaarListener:hidraw3] solaar.listener: status_changed <BoltReceiver(/dev/hidraw3,T14)>: present (1) pairing lock is open
2024-06-24 12:40:24,639,639    DEBUG [MainThread] solaar.ui: status changed: <BoltReceiver(/dev/hidraw3,T17)> (NOTIFICATION) pairing lock is open
2024-06-24 12:40:25,470,470    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4D06 303030303232983788BE3CD300000000]
2024-06-24 12:40:25,471,471    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E00 983788BE3CD300000000000000000000]
2024-06-24 12:40:25,619,619    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:26,119,119    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:26,620,620    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:27,120,120    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:27,621,621    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:28,122,122    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:28,623,623    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:29,124,124    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:29,625,625    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:30,125,125    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:30,626,626    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:31,127,127    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:31,627,627    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:32,128,128    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:32,628,628    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:33,129,129    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:33,630,630    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:34,131,131    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:34,632,632    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:35,132,132    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:35,632,632    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:36,133,133    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:36,633,633    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:37,134,134    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:37,634,634    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:38,134,134    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:38,635,635    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:39,135,135    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:39,636,636    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:40,137,137    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:40,637,637    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:41,138,138    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:41,639,639    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:42,139,139    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:42,242,242    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:42,639,639    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:42,917,917    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:43,139,139    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:43,640,640    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:43,825,825    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:44,140,140    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:44,552,552    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:44,640,640    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:45,140,140    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:45,205,205    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:45,641,641    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:45,857,857    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:46,142,142    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:46,642,642    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:46,862,862    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:47,143,143    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:47,644,644    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:47,852,852    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:48,144,144    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:48,467,467    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:48,644,644    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:49,120,120    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E01 983788BE3CD300000000000000000000]
2024-06-24 12:40:49,145,145    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:49,645,645    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> show passkey: 000022
2024-06-24 12:40:49,833,833    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 4E04 983788BE3CD300000000000000000000]
2024-06-24 12:40:49,855,855    DEBUG [SolaarListener:hidraw3] logitech_receiver.base: (14) => r[11 FF 5402 02983788BE3CD3000000000000000000]
2024-06-24 12:40:49,855,855     INFO [SolaarListener:hidraw3] logitech_receiver.notifications: <BoltReceiver(/dev/hidraw3,T14)>: pairing lock is closed
2024-06-24 12:40:49,855,855  WARNING [SolaarListener:hidraw3] logitech_receiver.notifications: pairing error 2: failed
2024-06-24 12:40:49,855,855     INFO [SolaarListener:hidraw3] solaar.listener: status_changed <BoltReceiver(/dev/hidraw3,T14)>: present (1) pairing lock is closed
2024-06-24 12:40:49,855,855    DEBUG [MainThread] solaar.ui: status changed: <BoltReceiver(/dev/hidraw3,T17)> (NOTIFICATION) pairing lock is closed
2024-06-24 12:40:50,146,146    DEBUG [MainThread] solaar.ui.pair_window: <BoltReceiver(/dev/hidraw3,T17)> fail: failed
pfps commented 3 months ago

OK. I'll see if I can trigger an error when I have access to the device.

Looking at the logs it appears that everything is going OK until the end. There are ten button presses and then the termination press, but there is no information available about what buttons were pressed.

chrisequalsdev commented 2 months ago

I was able to pair a 3s on Ubuntu 22.04 with Solaar 1.1.1. Discovered this issue because I couldn't get it to detect but then realized I had to hold the pair button down. After that it was detected and I (somehow) got the key button sequence right the first time.