8000 UHK80 crash when cancelling pairing request code entry prompt · Issue #1235 · UltimateHackingKeyboard/firmware · GitHub
[go: up one dir, main page]
More Web Proxy on the site http://driver.im/
Skip to content
8000

UHK80 crash when cancelling pairing request code entry prompt #1235

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Open
pcooke9 opened this issue May 16, 2025 · 6 comments
Open

UHK80 crash when cancelling pairing request code entry prompt #1235

pcooke9 opened this issue May 16, 2025 · 6 comments

Comments

@pcooke9
Copy link
pcooke9 commented May 16, 2025

FW v14.0.0-e70a7ca [master]
Both USBs & bridge cable connected
Dongle plugged into PC, but not in use

Tried connecting to my TV via BLE. (Samsung Q80T)
•UHK80 showed the pairing code prompt, but the TV didn't offer a pairing code.
•Tapped "ESC" to close pairing code prompt, but UHK80 restarted with the following error appearing in Agent:

Bt: Bt security failed: n/a (n/a, bc:7e:8b:d1:ee:19), level 1, err 1, disconnecting

Bt: Pairing of auth conn failed because of 1

Bt: Pairing failed: n/a (n/a, bc:7e:8b:d1:ee:19), reason 1

ASSERTION FAIL @ WEST_TOPDIR/zephyr/subsys/bluetooth/host/conn.c:1522
os: r0/a1:  0x00000004  r1/a2:  0x000005f2  r2/a3:  0x00000004
os: r3/a4:  0x00000004 r12/ip:  0x00000010 r14/lr:  0x0003bf5b
os:  xpsr:  0x01000000
os: r4/v1:  0xffffffff  r5/v2:  0x200155d4  r6/v3:  0x2002ccbe
os: r7/v4:  0x00000001  r8/v5:  0x00066fb9  r9/v6:  0x20001744
os: r10/v7: 0x20002414  r11/v8: 0x20002418    psp:  0x200337a8
os: EXC_RETURN: 0x0
os: Faulting instruction address (r15/pc): 0x00075072
os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
os: Current thread: 0x2000f3e0 (main)
fatal_error: Resetting system
@pcooke9
Copy link
Author
pcooke9 commented May 16, 2025

A second pairing attempt was "successful"...

•I was able to enter the pairing code and added the TV to Agent's connections list. The TV showed it was paired to "UHK 80 Right NUS", but no UHK output was sent to the TV.
•After rebooting the TV and the UHK80, I couldn't switch from the PC's USB, to the TV's BLE connection.

UART_Log_(2nd attempt)
[2025-05-16 01:45:12] Batteries: 71% 80% (3838 3794)
[2025-05-16 01:45:12] Bt: start advertising
[2025-05-16 01:45:12] Batteries: 71% 80% (3862 3794)
[2025-05-16 01:45:14] [00:01:04.826,019] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:14]
[2025-05-16 01:45:14] [00:01:04.826,354] <wrn> Bt: Refusing connenction n/a (DONGLE, e7:8a:76:d1:1a:50) (this is not a selected connection)
[2025-05-16 01:45:14]
[2025-05-16 01:45:14] Bt: start advertising
[2025-05-16 01:45:14] Batteries: 71% 80% (3868 3794)
[2025-05-16 01:45:15] [00:01:05.975,189] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:15]
[2025-05-16 01:45:15] Bt: start advertising
[2025-05-16 01:45:15] [00:01:06.997,467] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:16]
[2025-05-16 01:45:16] Bt: start advertising
[2025-05-16 01:45:16] [00:01:07.974,578] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:16]
[2025-05-16 01:45:16] Bt: start advertising
[2025-05-16 01:45:18] [00:01:08.996,124] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:18]
[2025-05-16 01:45:18] Bt: start advertising
[2025-05-16 01:45:18] [00:01:09.921,966] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:19]
[2025-05-16 01:45:19] Bt: start advertising
[2025-05-16 01:45:19] [00:01:10.968,505] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:20]
[2025-05-16 01:45:20] Bt: start advertising
[2025-05-16 01:45:22] [00:01:12.640,930] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:22]
[2025-05-16 01:45:22] Bt: start advertising
[2025-05-16 01:45:22] [00:01:13.671,905] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:23]
[2025-05-16 01:45:23] Bt: start advertising
[2025-05-16 01:45:23] [00:01:14.705,261] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:24]
[2025-05-16 01:45:24] Bt: start advertising
[2025-05-16 01:45:24] [00:01:15.631,896] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:24]
[2025-05-16 01:45:24] Bt: start advertising
[2025-05-16 01:45:24] [00:01:16.356,414] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:24]
[2025-05-16 01:45:24] Bt: start advertising
[2025-05-16 01:45:25] Bt: start advertising
[2025-05-16 01:45:25] [00:01:17.342,437] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:27]
[2025-05-16 01:45:27] Bt: start advertising
[2025-05-16 01:45:27] [00:01:18.591,979] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:28]
[2025-05-16 01:45:28] Bt: start advertising
[2025-05-16 01:45:28] [00:01:19.660,156] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:28]
[2025-05-16 01:45:28] Bt: start advertising
[2025-05-16 01:45:28] [00:01:20.387,451] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:28]
[2025-05-16 01:45:28] Bt: start advertising
[2025-05-16 01:45:29] [00:01:21.103,668] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:29]
[2025-05-16 01:45:29] Bt: start advertising
[2025-05-16 01:45:30] [00:01:22.330,932] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:30]
[2025-05-16 01:45:30] Bt: start advertising
[2025-05-16 01:45:31] [00:01:23.410,614] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:31]
[2025-05-16 01:45:31] Bt: start advertising
[2025-05-16 01:45:33] [00:01:24.450,408] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:33]
[2025-05-16 01:45:33] Bt: start advertising
[2025-05-16 01:45:33] [00:01:25.135,284] <wrn> Bt: Bt security failed: n/a (n/a, bc:7e:8b:d1:ee:19), level 3, err 0, disconnecting
[2025-05-16 01:45:33]
[2025-05-16 01:45:33] --- 1 messages dropped ---
[2025-05-16 01:45:33] [00:01:25.272,003] <wrn> Bt: Pairing completed: n/a (n/a, bc:7e:8b:d1:ee:19), bonded 1
[2025-05-16 01:45:33]
[2025-05-16 01:45:33] --- Pairing ended, success = 1: Successfuly bonded! ---
[2025-05-16 01:45:33] Conn state: Host22(New Bluetooth Device, Connected), Peer host1
[2025-05-16 01:45:33] Conn state: Host22(New Bluetooth Device, Ready), Peer host1
[2025-05-16 01:45:33] Conn state: Host22(New Bluetooth Device, Ready), Peer host1, Active
[2025-05-16 01:45:33] Bt: start advertising
[2025-05-16 01:45:33] Conn state: Host1(DONGLE, Connected), Peer host2
[2025-05-16 01:45:34] Conn state: Host1(DONGLE, Ready), Peer host2
[2025-05-16 01:45:34] Bt: start advertising
[2025-05-16 01:45:38] Bt: filling adv allow filter
[2025-05-16 01:45:38] [00:01:29.425,537] <inf> hogp: set protocol: 1
[2025-05-16 01:45:42] [00:01:29.425,659] <inf> hogp: starting HID app REPORT: 1
[2025-05-16 01:45:42] Flashed User Config version: 9.99.0 (native version: 11.0.0., at e70a7ca / 148d3566eff0433632ddf44991a7a15b)
[2025-05-16 01:45:42] Setting battery mode to 1
[2025-05-16 01:45:42] Clearing bonds
[2025-05-16 01:45:42]  - Keeping a known bond.
[2025-05-16 01:45:42]  - Keeping a known bond.
[2025-05-16 01:45:42]  - Keeping a known bond.
[2025-05-16 01:45:42]  - Keeping a known bond.
[2025-05-16 01:45:42] Resetting left right link! Unidirectional
[2025-05-16 01:45:42] --- Pairing ended, success = 0: Pairing mode off. ---
[2025-05-16 01:45:42] Resetting left right link! Unidirectional
[2025-05-16 01:45:42] Bt: start advertising
[2025-05-16 01:45:42] Bt: filling adv allow filter
[2025-05-16 01:45:43] Batteries: 71% 80% (3868 3794)
[2025-05-16 01:45:43] [00:01:35.458,526] <inf> Battery: ... Powered, not charging, not correcting: 3792 (82)
[2025-05-16 01:45:43] [00:01:35.458,587] <inf> Battery:     ... Averaged to 3793 (82)
[2025-05-16 01:45:43] Step called with 80 82 -> 80
[2025-05-16 01:45:43] corrected voltage is 3793 80
[2025-05-16 01:45:43] Batteries: 71% 80% (3868 3793)
[2025-05-16 01:45:45] Batteries: 71% 80% (3864 3793)
[2025-05-16 01:46:11] [00:02:02.952,514] <wrn> bt_att: No ATT channel for MTU 32
[2025-05-16 01:46:12] [00:02:02.952,728] <wrn> bt_gatt: No buffer available to send notification
[2025-05-16 01:46:12] keyboard NKRO mode fails, falling back to 6KRO
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:12] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:13] Giving report to c2usb ble hid!
[2025-05-16 01:46:14] Giving report to c2usb ble hid!
[2025-05-16 01:46:14] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:15] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:16] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:17] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:18] Giving report to c2usb ble hid!
[2025-05-16 01:46:19] Giving report to c2usb ble hid!
[2025-05-16 01:46:19] Giving report to c2usb ble hid!
[2025-05-16 01:46:19] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] Giving report to c2usb ble hid!
[2025-05-16 01:46:44] [00:02:35.958,892] <inf> Battery: ... Powered, not charging, not correcting: 3782 (81)
[2025-05-16 01:46:46] [00:02:35.959,136] <inf> Battery:     ... Averaged to 3789 (82)
[2025-05-16 01:46:46] Step called with 80 82 -> 80
[2025-05-16 01:46:46] corrected voltage is 3789 80
[2025-05-16 01:46:46] Batteries: 71% 80% (3864 3789)
[2025-05-16 01:46:46] Batteries: 71% 80% (3866 3789)
[2025-05-16 01:46:53] Conn state: Host3(Bluetooth device, Disconnected), Peer host1, Active
[2025-05-16 01:47:45] Conn state: Host0(PC, Ready), Active
[2025-05-16 01:47:45] [00:02:44.874,877] <inf> hogp: stopping HID app 1
[2025-05-16 01:47:45] Bt: start advertising
[2025-05-16 01:47:45] Bt: filling adv allow filter
[2025-05-16 01:47:45] [00:03:36.459,411] <inf> Battery: ... Powered, not charging, not correcting: 3780 (81)
[2025-05-16 01:47:46] [00:03:36.459,503] <inf> Battery:     ... Averaged to 3787 (81)
[2025-05-16 01:47:46] Step called with 80 81 -> 80
[2025-05-16 01:47:46] corrected voltage is 3787 80
[2025-05-16 01:47:46] Batteries: 71% 80% (3866 3787)
[2025-05-16 01:47:46] Batteries: 71% 80% (3864 3787)
[2025-05-16 01:48:39] Bt: start advertising
[2025-05-16 01:48:45] [00:04:36.958,892] <inf> Battery: ... Powered, not charging, not correcting: 3786 (81)
[2025-05-16 01:48:47] [00:04:36.959,014] <inf> Battery:     ... Averaged to 3786 (81)
[2025-05-16 01:48:47] Step called with 80 81 -> 80
[2025-05-16 01:48:47] corrected voltage is 3786 80
[2025-05-16 01:48:47] Batteries: 71% 80% (3864 3786)
[2025-05-16 01:48:47] Batteries: 71% 80% (3865 3786)
[2025-05-16 01:49:28] Conn state: Host3(Bluetooth device, Disconnected), Selected
[2025-05-16 01:49:46] Bt: start advertising
[2025-05-16 01:49:46] [00:05:37.459,838] <inf> Battery: ... Powered, not charging, not correcting: 3782 (81)
[2025-05-16 01:49:47] [00:05:37.459,930] <inf> Battery:     ... Averaged to 3786 (81)
[2025-05-16 01:49:47] Step called with 80 81 -> 80
[2025-05-16 01:49:47] corrected voltage is 3786 80
[2025-05-16 01:49:47] Batteries: 71% 80% (3864 3786)
[2025-05-16 01:49:56] uhk80:right$
[2025-05-16 01:49:56] --- 498 messages dropped ---
[2025-05-16 01:49:56] Uart, unexpected byte: 0
[2025-05-16 01:49:56] Uart, unexpected byte: 0
[2025-05-16 01:49:56] UART_RX_DISABLED
[2025-05-16 01:49:56] [00:00:00.095,184] <inf> fs_nvs: 3 Sectors of 4096 bytes
[2025-05-16 01:49:56] [00:00:00.095,214] <inf> fs_nvs: alloc wra: 0, e30
[2025-05-16 01:49:56] [00:00:00.095,245] <inf> fs_nvs: data wra: 0, 76c
[2025-05-16 01:49:56] [00:00:00.095,886] <inf> bt_sdc_hci_driver: SoftDevice Controller build revision:
[2025-05-16 01:49:56]                                             fe 2c f9 6a 7f 36 22 2e  a0 79 c0 40 be 2c 03 20 |.,.j.6". .y.@.,.
[2025-05-16 01:49:56]                                             40 c2 f3 32                                      |@..2
[2025-05-16 01:49:56] [00:00:00.101,104] <inf> bt_hci_core: HW Platform: Nordic Semiconductor (0x0002)
[2025-05-16 01:49:56] [00:00:00.101,165] <inf> bt_hci_core: HW Variant: nRF52x (0x0002)
[2025-05-16 01:49:56] [00:00:00.101,196] <inf> bt_hci_core: Firmware: Standard Bluetooth controller (0x00) Version 254.63788 Build 573996906
[2025-05-16 01:49:57] [00:00:00.102,142] <inf> bt_hci_core: No ID address. App must call settings_load()
[2025-05-16 01:49:57] Settings: Found peer 'left' with address 367fe58da8ec
[2025-05-16 01:49:57] [00:00:00.117,706] <inf> bt_hci_core: Identity: D5:13:F6:62:18:21 (random)
[2025-05-16 01:49:57] [00:00:00.117,767] <inf> bt_hci_core: HCI: version 6.0 (0x0e) revision 0x104e, manufacturer 0x0059
[2025-05-16 01:49:57] [00:00:00.117,797] <inf> bt_hci_core: LMP: version 6.0 (0x0e) subver 0x104e
[2025-05-16 01:49:57] Reading hardware config
[2025-05-16 01:49:57] Reading user config
[2025-05-16 01:49:57] Applying user config
[2025-05-16 01:49:57] Flashed User Config version: 9.99.0 (native version: 11.0.0., at e70a7ca / 148d3566eff0433632ddf44991a7a15b)
[2025-05-16 01:49:57] Setting battery mode to 1
[2025-05-16 01:49:57] Clearing bonds
[2025-05-16 01:49:57]  - Keeping a known bond.
[2025-05-16 01:49:57]  - Keeping a known bond.
[2025-05-16 01:49:57]  - Keeping a known bond.
[2025-05-16 01:49:57]  - Keeping a known bond.
[2025-05-16 01:49:57] Resetting left right link! Unidirectional
[2025-05-16 01:49:57] User config applied
[2025-05-16 01:49:57] Scan module initialized
[2025-05-16 01:49:57] Bt: filling scan filters
[2025-05-16 01:49:57] --- Pairing ended, success = 0: Pairing mode off. ---
[2025-05-16 01:49:57] Enabling UART
[2025-05-16 01:49:57] USB config changing to Hid_NoGamepad
[2025-05-16 01:49:57] Bt: filling scan filters
[2025-05-16 01:49:57] Conn state: UartLeft(Ready)
[2025-05-16 01:49:57] Resetting left right link! Bidirectional
[2025-05-16 01:49:57] [00:00:00.420,074] <wrn> udc_nrf: USB Address incorrect 0x04
[2025-05-16 01:49:57] Filters matched: left (n/a, ec:a8:8d:e5:7f:36), connectable:1
[2025-05-16 01:49:57] Scan connecting: left (n/a, ec:a8:8d:e5:7f:36)
[2025-05-16 01:49:57] Conn state: Host0(PC, Ready)
[2025-05-16 01:49:57] Conn state: Host0(PC, Ready), Active
[2025-05-16 01:49:57] [00:00:00.595,703] <wrn> bt_smp: Unexpected SMP code 0x0b
[2025-05-16 01:49:57] Uart: didn't receive ack 614, resending
[2025-05-16 01:49:57] Batteries: 72% 0% (3860 0)
[2025-05-16 01:49:57] Conn state: NusServerLeft(Connected), Peer left
[2025-05-16 01:49:57] [00:00:01.385,437] <inf> Battery: ... Voltage corrected because of charging 3922 -> 3786 (81) -> 3769 (80)
[2025-05-16 01:49:57] [00:00:01.385,528] <inf> Battery:     ... Averaged to 3769 (80)
[2025-05-16 01:49:57] corrected voltage is 3769 80
[2025-05-16 01:49:57] Batteries: 72% 80% (3860 3769)
[2025-05-16 01:49:57] Conn state: NusServerLeft(Ready), Peer left
[2025-05-16 01:49:57] Bt: start advertising
[2025-05-16 01:49:57] Bt: filling adv allow filter
[2025-05-16 01:49:59] [00:00:02.586,273] <inf> Battery: ... Powered, not charging, not correcting: 3794 (82)
[2025-05-16 01:49:59] [00:00:02.586,334] <inf> Battery:     ... Averaged to 3794 (82)
[2025-05-16 01:49:59] Step called with 80 82 -> 80
[2025-05-16 01:50:00] corrected voltage is 3794 80
[2025-05-16 01:50:00] Batteries: 72% 80% (3860 3794)
[2025-05-16 01:50:00] Batteries: 72% 80% (3846 3794)
[2025-05-16 01:50:02] [00:00:05.612,243] <wrn> bt_hci_core: opcode 0x2013 status 0x3a
[2025-05-16 01:50:03] Bt: start advertising
[2025-05-16 01:50:05] Conn state: Host1(DONGLE, Connected), Peer host1
[2025-05-16 01:50:09] Conn state: Host1(DONGLE, Ready), Peer host1
[2025-05-16 01:50:09] Bt: start advertising
[2025-05-16 01:50:09] Conn state: Host3(Bluetooth device, Disconnected), Selected
[2025-05-16 01:50:59] Bt: start advertising
[2025-05-16 01:50:59] [00:01:03.086,059] <inf> Battery: ... Powered, not charging, not correcting: 3788 (81)
[2025-05-16 01:51:01] [00:01:03.086,181] <inf> Battery:     ... Averaged to 3788 (81)

Of course, I don't really know what I'm talking about, but the following section from the "UART_Log_(2nd attempt)" seems weird to me. Shouldn't "Host22" be "Host3"? (bc:7e:8b:d1:ee:19 is the TV) I only have a few BLE devices and one dongle, definitely not 22 hosts:

[2025-05-16 01:45:31] Bt: start advertising
[2025-05-16 01:45:33] [00:01:24.450,408] <wrn> Bt: Refusing authenticated connenction 2 (this is not a selected connection)
[2025-05-16 01:45:33]
[2025-05-16 01:45:33] Bt: start advertising
[2025-05-16 01:45:33] [00:01:25.135,284] <wrn> Bt: Bt security failed: n/a (n/a, bc:7e:8b:d1:ee:19), level 3, err 0, disconnecting
[2025-05-16 01:45:33]
[2025-05-16 01:45:33] --- 1 messages dropped ---
[2025-05-16 01:45:33] [00:01:25.272,003] <wrn> Bt: Pairing completed: n/a (n/a, bc:7e:8b:d1:ee:19), bonded 1
[2025-05-16 01:45:33]
[2025-05-16 01:45:33] --- Pairing ended, success = 1: Successfuly bonded! ---
[2025-05-16 01:45:33] Conn state: Host22(New Bluetooth Device, Connected), Peer host1
[2025-05-16 01:45:33] Conn state: Host22(New Bluetooth Device, Ready), Peer host1
[2025-05-16 01:45:33] Conn state: Host22(New Bluetooth Device, Ready), Peer host1, Active

@pcooke9
Copy link
Author
pcooke9 commented May 29, 2025

After using "Reset device configuration" in Agent, my phone tried to pair repeatedly, even when I hit cancel on the phone. It kept trying, so I also tapped "ESC" on the UHK80, and it crashed with another error message in Agent:

Bt: Bt security failed: n/a (n/a, 66:29:a3:df:ae:ef), level 1, err 1, disconnecting

Bt: Pairing of auth conn failed because of 1

Bt: Pairing failed: n/a (n/a, 66:29:a3:df:ae:ef), reason 1

ASSERTION FAIL @ WEST_TOPDIR/zephyr/subsys/bluetooth/host/conn.c:1522
os: r0/a1:  0x00000004  r1/a2:  0x000005f2  r2/a3:  0x00000004
os: r3/a4:  0x00000004 r12/ip:  0x00000010 r14/lr:  0x0003bf77
os:  xpsr:  0x01000000
os: r4/v1:  0xffffffff  r5/v2:  0x20015624  r6/v3:  0x2002cd10
os: r7/v4:  0x00000001  r8/v5:  0x000670cd  r9/v6:  0x20001750
os: r10/v7: 0x20002420  r11/v8: 0x20002424    psp:  0x200337e8
os: EXC_RETURN: 0x0
os: Faulting instruction address (r15/pc): 0x000751a2
os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
os: Current thread: 0x2000f3f0 (main)
fatal_error: Resetting system
Printing trace buffer because: Looks like the firmware freezed. If that is the case, please report bellow trace to the devs:

Last EV: 0
Trace:
()()()()()()()()I2,1,20,13879()OI2,1,32,15O()()()(l7l8)O()()()()
()()()()()()()()(l7l8)O()()()()()()(l7l8)O()()()()()()()()()()(l
7l8)O()()()()()()()(l7l8)O()()()()()()()()()()()()(l7l8)O()()()(
)()()()()I2,2,6,22229(<>l6u1u2v3w0w1x1x2x3x4w2w3w4w5w6ve3u3u6###

I also had the UART adapter connected this time:

UART_adapter_output
[2025-05-28 21:48:27] Flashed User Config version: 9.99.0 (native version: 11.0.0., at ed422fb / f09ffa0a7d1a890a093720318d9718dd)
[2025-05-28 21:48:27] Setting battery mode to 0
[2025-05-28 21:48:27] Clearing bonds
[2025-05-28 21:48:27]  - Deleting an unknown bond!
[2025-05-28 21:48:27] Unpaired device BC:7E:8B:D1:EE:19 (public)
[2025-05-28 21:48:27]  - Deleting an unknown bond!
[2025-05-28 21:48:27] Unpaired device E7:8A:76:D1:1A:50 (random)
[2025-05-28 21:48:27]  - Deleting an unknown bond!
[2025-05-28 21:48:27] Unpaired device 94:E1:29:C9:80:67 (public)
[2025-05-28 21:48:27] Resetting left right link! Unidirectional
[2025-05-28 21:48:27] Resetting left right link! Unidirectional
[2025-05-28 21:48:28] Batteries: 87% 92% (4085 4008)
[2025-05-28 21:48:29] [00:02:34.594,299] <inf> Battery: ... Powered, not charging, not correcting: 4006 (92)
[2025-05-28 21:48:29] [00:02:34.594,360] <inf> Battery:     ... Averaged to 4007 (92)
[2025-05-28 21:48:29] corrected voltage is 4007 92
[2025-05-28 21:48:30] Batteries: 87% 92% (4085 4007)
[2025-05-28 21:48:30] [00:02:35.796,478] <inf> Battery: ... Voltage corrected because of charging 4050 -> 4004 (92) -> 3999 (92)
[2025-05-28 21:48:30] [00:02:35.796,539] <inf> Battery:     ... Averaged to 4005 (92)
[2025-05-28 21:48:30] corrected voltage is 4005 92
[2025-05-28 21:48:30] Batteries: 87% 92% (4085 4005)
[2025-05-28 21:48:30] Batteries: 87% 92% (4085 4005)
[2025-05-28 21:48:32] Batteries: 87% 92% (4084 4005)
[2025-05-28 21:48:34] --- Entering pairing mode. Going to stop BT and disconnect all connections. ---
[2025-05-28 21:48:34] Settings: Found peer 'left' with address 367fe58da8ec
[2025-05-28 21:48:35] Bt: start advertising
[2025-05-28 21:48:35] Settings: Found peer 'left' with address 367fe58da8ec
[2025-05-28 21:48:35] Bt: start advertising
[2025-05-28 21:48:35] Waiting for central to pair to me.
[2025-05-28 21:48:35] Bt: start advertising
[2025-05-28 21:48:37] Bt: start advertising
[2025-05-28 21:48:37] [00:02:42.544,067] <wrn> Bt: Pairing completed: n/a (n/a, e7:8a:76:d1:1a:50), bonded 1
[2025-05-28 21:48:37]
[2025-05-28 21:48:37] --- Pairing ended, success = 1: Successfuly bonded! ---
[2025-05-28 21:48:37] [00:02:42.547,912] <wrn> Bt: Authenticated connection is not known. Disconnecting n/a (n/a, e7:8a:76:d1:1a:50)
[2025-05-28 21:48:37] Bt: start advertising
[2025-05-28 21:48:37] Bt: start advertising
[2025-05-28 21:48:37] Bt: start advertising
[2025-05-28 21:48:37] [00:02:43.515,441] <wrn> Bt: Authenticated connection is not known. Disconnecting n/a (n/a, e7:8a:76:d1:1a:50)
[2025-05-28 21:48:38] Bt: start advertising
[2025-05-28 21:48:38] Bt: start advertising
[2025-05-28 21:48:38] Bt: start advertising
[2025-05-28 21:48:38] [00:02:44.276,947] <wrn> Bt: Authenticated connection is not known. Disconnecting n/a (n/a, e7:8a:76:d1:1a:50)
[2025-05-28 21:48:38] Bt: start advertising
[2025-05-28 21:48:38] Bt: start advertising
[2025-05-28 21:48:38] Flashed User Config version: 9.99.0 (native version: 11.0.0., at ed422fb / f09ffa0a7d1a890a093720318d9718dd)
[2025-05-28 21:48:38] Setting battery mode to 0
[2025-05-28 21:48:38] Clearing bonds
[2025-05-28 21:48:38]  - Keeping a known bond.
[2025-05-28 21:48:39] Resetting left right link! Unidirectional
[2025-05-28 21:48:39] Bt: start advertising
[2025-05-28 21:48:39] Conn state: Host1(Dongle, Connected), Peer host1
[2025-05-28 21:48:39] Conn state: Host1(Dongle, Disconnected), Peer host1
[2025-05-28 21:48:40] Bt: start advertising
[2025-05-28 21:48:40] [00:02:46.274,108] <inf> Battery: ... Voltage corrected because of charging 4132 -> 4012 (92) -> 3997 (92)
[2025-05-28 21:48:45] [00:02:46.274,230] <inf> Battery:     ... Averaged to 4004 (92)
[2025-05-28 21:48:45] corrected voltage is 4004 92
[2025-05-28 21:48:45] Batteries: 87% 92% (4084 4004)
[2025-05-28 21:48:45] [00:02:46.458,282] <wrn> Bt: Bt security failed: n/a (Dongle, e7:8a:76:d1:1a:50), level 1, err 9, disconnecting
[2025-05-28 21:48:45]
[2025-05-28 21:48:45] Bt: start advertising
[2025-05-28 21:48:45] Conn state: Host1(Dongle, Connected), Peer host1
[2025-05-28 21:48:46] Conn state: Host1(Dongle, Ready), Peer host1
[2025-05-28 21:48:46] Bt: start advertising
[2025-05-28 21:48:46] [00:02:52.358,428] <err> bt_smp: pairing failed (peer reason 0x8)
[2025-05-28 21:48:47] [00:02:52.376,983] <wrn> Bt: Bt security failed: n/a (n/a, 6a:95:c2:7d:e8:3f), level 1, err 9, disconnecting
[2025-05-28 21:48:50]
[2025-05-28 21:48:50] Bt: start advertising
[2025-05-28 21:48:54] Bt: start advertising
[2025-05-28 21:48:54] [00:02:59.427,307] <err> bt_smp: pairing failed (peer reason 0x8)
[2025-05-28 21:48:54] [00:02:59.446,014] <wrn> Bt: Bt security failed: n/a (n/a, 7b:34:20:2d:9d:81), level 1, err 9, disconnecting
[2025-05-28 21:48:54]
[2025-05-28 21:48:57] Bt: start advertising
[2025-05-28 21:49:00] [00:03:06.398,193] <err> bt_smp: pairing failed (peer reason 0x8)
[2025-05-28 21:49:01] [00:03:06.417,236] <wrn> Bt: Bt security failed: n/a (n/a, 6e:05:c1:97:e0:09), level 1, err 9, disconnecting
[2025-05-28 21:49:01]
[2025-05-28 21:49:04] Bt: start advertising
[2025-05-28 21:49:04] Bt: start advertising
[00:03:13.817,474] <wrn> Bt: Bt security failed: n/a (n/a, 66:29:a3:df:ae:ef), level 1, err 1, disconnecting
[2025-05-28 21:49:07]
[2025-05-[00:03:13.817,596] <wrn> Bt: Pairing of auth conn failed because of 1
[2025-05-28 21:49:07]
[2025-05-[00:03:13.839,782] <wrn> Bt: Pairing failed: n/a (n/a, 66:29:a3:df:ae:ef), reason 1
[2025-05-28 21:49:07]
[2025-05-ASSERTION FAIL @ WEST_TOPDIR/zephyr/subsys/bluetooth/host/conn.c:1522
[2025-05-[00:03:13.840,423] <err> os: r0/a1:  0x00000004  r1/a2:  0x000005f2  r2/a3:  0x00000004
[2025-05-[00:03:13.840,454] <err> os: r3/a4:  0x00000004 r12/ip:  0x00000010 r14/lr:  0x0003bf77
[2025-05-[00:03:13.840,454] <err> os:  xpsr:  0x01000000
[2025-05-[00:03:13.840,484] <err> os: r4/v1:  0xffffffff  r5/v2:  0x20015624  r6/v3:  0x2002cd10
[2025-05-[00:03:13.840,515] <err> os: r7/v4:  0x00000001  r8/v5:  0x000670cd  r9/v6:  0x20001750
[2025-05-[00:03:13.840,576] <err> os: r10/v7: 0x20002420  r11/v8: 0x20002424    psp:  0x200337e8
[2025-05-[00:03:13.840,606] <err> os: EXC_RETURN: 0x0
[2025-05-[00:03:13.840,637] <err> os: Faulting instruction address (r15/pc): 0x000751a2
[2025-05-[00:03:13.840,667] <err> os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
[2025-05-[00:03:13.840,698] <err> os: Current thread: 0x2000f3f0 (main)
[2025-05-[00:03:14.029,663] <err> fatal_error: Resetting system
[2025-05-28 21:49:08] uhk80:right$
[2025-05-28 21:49:08] *** Booting nRF Connect SDK v2.8.0-02c754dcc5f2 ***
[2025-05-28 21:49:08] *** Using Zephyr OS v3.7.99-4f422f1ee1ef ***
[2025-05-28 21:49:09] Wormhole is open, reboot to power mode 0 0
[2025-05-28 21:49:09] Going to resume!
[2025-05-28 21:49:09] ----------
[2025-05-28 21:49:09] UHK 80 Right USB started
[2025-05-28 21:49:09] [00:00:00.056,427] <inf> fs_nvs: 3 Sectors of 4096 bytes
[2025-05-28 21:49:09] [00:00:00.056,457] <inf> fs_nvs: alloc wra: 0, e20
[2025-05-28 21:49:09] [00:00:00.056,457] <inf> fs_nvs: data wra: 0, 32c
[2025-05-28 21:49:09] [00:00:00.057,098] <inf> bt_sdc_hci_driver: SoftDevice Controller build revision:
[2025-05-28 21:49:09]                                             fe 2c f9 6a 7f 36 22 2e  a0 79 c0 40 be 2c 03 20 |.,.j.6". .y.@.,.
[2025-05-28 21:49:09]                                             40 c2 f3 32                                      |@..2
[2025-05-28 21:49:09] --- 1 messages dropped ---
[2025-05-28 21:49:09] [00:00:00.062,377] <inf> bt_hci_core: HW Platform: Nordic Semiconductor (0x0002)
[2025-05-28 21:49:09] [00:00:00.062,438] <inf> bt_hci_core: HW Variant: nRF52x (0x0002)
[2025-05-28 21:49:09] [00:00:00.062,469] <inf> bt_hci_core: Firmware: Standard Bluetooth controller (0x00) Version 254.63788 Build 573996906
[2025-05-28 21:49:09] [00:00:00.063,476] <inf> bt_hci_core: No ID address. App must call settings_load()
[2025-05-28 21:49:09] Settings: Found peer 'left' with address 367fe58da8ec
[2025-05-28 21:49:09] [00:00:00.071,166] <inf> bt_hci_core: Identity: D5:13:F6:62:18:21 (random)
[2025-05-28 21:49:09] [00:00:00.071,228] <inf> bt_hci_core: LMP: version 6.0 (0x0e) subver 0x104e
[2025-05-28 21:49:09] Reading hardware config
[2025-05-28 21:49:09] Reading user config
[2025-05-28 21:49:09] Applying user config
[2025-05-28 21:49:09] Flashed User Config version: 9.99.0 (native version: 11.0.0., at ed422fb / f09ffa0a7d1a890a093720318d9718dd)
[2025-05-28 21:49:09] Setting battery mode to 0
[2025-05-28 21:49:09] Clearing bonds
[2025-05-28 21:49:09]  - Keeping a known bond.
[2025-05-28 21:49:09] Resetting left right link! Unidirectional
[2025-05-28 21:49:09] User config applied
[2025-05-28 21:49:09] CRASH DETECTED, waiting for 5 seconds to allow Agent to reenumerate
[2025-05-28 21:49:09] USB config changing to Hid_NoGamepad
[2025-05-28 21:49:09] [00:00:00.371,368] <wrn> udc_nrf: USB Address incorrect 0x02
[2025-05-28 21:49:09] Conn state: Host0(My PC, Ready)
[2025-05-28 21:49:09] Conn state: Host0(My PC, Ready), Active
[2025-05-28 21:49:13] --- 464 messages dropped ---
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] Uart, unexpected byte: 0
[2025-05-28 21:49:13] UART_RX_DISABLED
[2025-05-28 21:49:13] Scan module initialized
[2025-05-28 21:49:13] Bt: filling scan filters
[2025-05-28 21:49:14] Enabling UART
[2025-05-28 21:49:14] Conn state: UartLeft(Ready)
[2025-05-28 21:49:14] Resetting left right link! Bidirectional
[2025-05-28 21:49:14] Filters matched: left (n/a, ec:a8:8d:e5:7f:36), connectable:1
[2025-05-28 21:49:14] Scan connecting: left (n/a, ec:a8:8d:e5:7f:36)
[2025-05-28 21:49:14] Batteries: 87% 0% (4084 0)
[2025-05-28 21:49:15] [00:00:05.592,681] <wrn> bt_smp: Unexpected SMP code 0x0b
[2025-05-28 21:49:16] [00:00:05.595,733] <wrn> Bt: Bt security failed: left (n/a, ec:a8:8d:e5:7f:36), level 1, err 9, disconnecting
[2025-05-28 21:49:16]
[2025-05-28 21:49:16] Batteries: 87% 0% (4084 0)
[2025-05-28 21:49:16] Batteries: 87% 0% (4084 0)
[2025-05-28 21:49:16] [00:00:05.691,772] <wrn> bt_smp: Unexpected SMP code 0x02
[2025-05-28 21:49:16] [00:00:06.849,090] <inf> Battery: ... Voltage corrected because of charging 4058 -> 4012 (92) -> 4007 (92)
[2025-05-28 21:49:16] [00:00:06.849,456] <inf> Battery:     ... Averaged to 4007 (92)
[2025-05-28 21:49:16] corrected voltage is 4007 92
[2025-05-28 21:49:20] Batteries: 87% 92% (4084 4007)
[2025-05-28 21:49:20] Batteries: 87% 92% (4087 4007)
[2025-05-28 21:49:20] [00:00:10.942,230] <wrn> bt_hci_core: opcode 0x2013 status 0x3a
< 8000 div class="pr-review-reactions ">

@pcooke9
Copy link
Author
pcooke9 commented May 30, 2025

And another crash when tapping "ESC" to cancel the pairing code entry prompt. Similar situation as the last, but this time my phone tried sending pairing requests immediately after importing a config, instead of using "Reset device configuration".
(I didn't initiate the pairing requests, they happened automatically🤷🏻‍♂️)

Agent crash log:

Bt: Bt security failed: n/a (n/a, 78:2f:b7:6f:d7:75), level 1, err 1, disconnecting

Bt: Pairing of auth conn failed because of 1

Bt: Pairing failed: n/a (n/a, 78:2f:b7:6f:d7:75), reason 1

ASSERTION FAIL @ WEST_TOPDIR/zephyr/subsys/bluetooth/host/conn.c:1522
os: r0/a1:  0x00000004  r1/a2:  0x000005f2  r2/a3:  0x00000004
os: r3/a4:  0x00000004 r12/ip:  0x00000010 r14/lr:  0x0003bf77
os:  xpsr:  0x01000000
os: r4/v1:  0xffffffff  r5/v2:  0x20015624  r6/v3:  0x2002cd10
os: r7/v4:  0x00000001  r8/v5:  0x000670cd  r9/v6:  0x20001750
os: r10/v7: 0x20002420  r11/v8: 0x20002424    psp:  0x200337e8
os: EXC_RETURN: 0x0
os: Faulting instruction address (r15/pc): 0x000751a2
os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
os: Current thread: 0x2000f3f0 (main)
fatal_error: Resetting system
UART_adapter_output
[2025-05-30 00:17:12] Bt: start advertising
[2025-05-30 00:17:12] Bt: start advertising
[2025-05-30 00:17:13] Conn state: Host2(Dongle, Connected), Peer host1
[2025-05-30 00:17:13] Conn state: Host2(Dongle, Ready), Peer host1
[2025-05-30 00:17:13] Bt: start advertising
[2025-05-30 00:17:21] [00:08:06.575,073] <inf> Battery: ... Powered, not charging, not correcting: 4068 (96)
[2025-05-30 00:17:21] [00:08:06.575,134] <inf> Battery:     ... Averaged to 4068 (96)
[2025-05-30 00:17:21] Step called with 95 96 -> 95
[2025-05-30 00:17:21] corrected voltage is 4068 95
[2025-05-30 00:17:24] [00:08:09.928,100] <err> bt_smp: pairing failed (peer reason 0x8)
[2025-05-30 00:17:24] [00:08:09.948,181] <wrn> Bt: Bt security failed: n/a (n/a, 41:68:04:b7:52:e0), level 1, err 9, disconnecting
[2025-05-30 00:17:25]
[2025-05-30 00:17:28] Bt: start advertising
[2025-05-30 00:17:28] Bt: start advertising
[2025-05-30 00:17:32] uhk80:right$
[00:08:19.002,288] <wrn> Bt: Bt security failed: n/a (n/a, 78:2f:b7:6f:d7:75), level 1, err 1, disconnecting
[2025-05-30 00:17:32]
[2025-05-[00:08:19.002,319] <wrn> Bt: Pairing of auth conn failed because of 1
[2025-05-30 00:17:32]
[2025-05-[00:08:19.028,808] <wrn> Bt: Pairing failed: n/a (n/a, 78:2f:b7:6f:d7:75), reason 1
[2025-05-30 00:17:33]
[2025-05-ASSERTION FAIL @ WEST_TOPDIR/zephyr/subsys/bluetooth/host/conn.c:1522
[2025-05-[00:08:19.029,418] <err> os: r0/a1:  0x00000004  r1/a2:  0x000005f2  r2/a3:  0x00000004
[2025-05-[00:08:19.029,418] <err> os: r3/a4:  0x00000004 r12/ip:  0x00000010 r14/lr:  0x0003bf77
[2025-05-[00:08:19.029,449] <err> os:  xpsr:  0x01000000
[2025-05-[00:08:19.029,449] <err> os: r4/v1:  0xffffffff  r5/v2:  0x20015624  r6/v3:  0x2002cd10
[2025-05-[00:08:19.029,479] <err> os: r7/v4:  0x00000001  r8/v5:  0x000670cd  r9/v6:  0x20001750
[2025-05-[00:08:19.029,479] <err> os: r10/v7: 0x20002420  r11/v8: 0x20002424    psp:  0x200337e8
[2025-05-[00:08:19.029,510] <err> os: EXC_RETURN: 0x0
[2025-05-[00:08:19.029,541] <err> os: Faulting instruction address (r15/pc): 0x000751a2
[2025-05-[00:08:19.029,571] <err> os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
[2025-05-[00:08:19.029,602] <err> os: Current thread: 0x2000f3f0 (main)
[2025-05-[00:08:19.200,897] <err> fatal_error: Resetting system
[2025-05-30 00:17:33] uhk80:right$
[2025-05-30 00:17:34] *** Booting nRF Connect SDK v2.8.0-02c754dcc5f2 ***
[2025-05-30 00:17:34] *** Using Zephyr OS v3.7.99-4f422f1ee1ef ***
[2025-05-30 00:17:34] Wormhole is open, reboot to power mode 0 0
[2025-05-30 00:17:34] Going to resume!
[2025-05-30 00:17:34] ----------
[2025-05-30 00:17:34] UHK 80 Right USB started
[2025-05-30 00:17:34] [00:00:00.057,830] <inf> fs_nvs: 3 Sectors of 4096 bytes
[2025-05-30 00:17:34] [00:00:00.057,830] <inf> fs_nvs: alloc wra: 1, f70
[2025-05-30 00:17:34] [00:00:00.057,830] <inf> fs_nvs: data wra: 1, 12c
[2025-05-30 00:17:34] [00:00:00.059,020] <inf> bt_sdc_hci_driver: SoftDevice Controller build revision:
[2025-05-30 00:17:34]                                             fe 2c f9 6a 7f 36 22 2e  a0 79 c0 40 be 2c 03 20 |.,.j.6". .y.@.,.
[2025-05-30 00:17:34]                                             40 c2 f3 32                                      |@..2
[2025-05-30 00:17:34] --- 1 messages dropped ---
[2025-05-30 00:17:34] [00:00:00.064,147] <inf> bt_hci_core: HW Platform: Nordic Semiconductor (0x0002)
[2025-05-30 00:17:34] [00:00:00.064,178] <inf> bt_hci_core: HW Variant: nRF52x (0x0002)
[2025-05-30 00:17:34] [00:00:00.064,208] <inf> bt_hci_core: Firmware: Standard Bluetooth controller (0x00) Version 254.63788 Build 573996906
[2025-05-30 00:17:35] [00:00:00.065,155] <inf> bt_hci_core: No ID address. App must call settings_load()
[2025-05-30 00:17:35] Settings: Found peer 'left' with address 367fe58da8ec
[2025-05-30 00:17:35] [00:00:00.072,387] <inf> bt_hci_core: Identity: D5:13:F6:62:18:21 (random)
[2025-05-30 00:17:35] [00:00:00.072,479] <inf> bt_hci_core: LMP: version 6.0 (0x0e) subver 0x104e
[2025-05-30 00:17:35] Reading hardware config
[2025-05-30 00:17:35] Reading user config
[2025-05-30 00:17:35] Applying user config
[2025-05-30 00:17:35] Flashed User Config version: 9.99.0 (native version: 11.0.0., at ed422fb / f09ffa0a7d1a890a093720318d9718dd)
[2025-05-30 00:17:35] Setting battery mode to 1
[2025-05-30 00:17:35] Clearing bonds
[2025-05-30 00:17:35]  - Keeping a known bond.
[2025-05-30 00:17:35] Resetting left right link! Unidirectional
[2025-05-30 00:17:35] User config applied
[2025-05-30 00:17:35] CRASH DETECTED, waiting for 5 seconds to allow Agent to reenumerate
[2025-05-30 00:17:35] USB config changing to Hid_NoGamepad
[2025-05-30 00:17:35] [00:00:00.365,844] <wrn> udc_nrf: USB Address incorrect 0x02
[2025-05-30 00:17:35] Conn state: Host0(PC, Ready)
[2025-05-30 00:17:35] Conn state: Host0(PC, Ready), Active
[2025-05-30 00:17:39] --- 465 messages dropped ---
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:39] Uart, unexpected byte: 0
[2025-05-30 00:17:40] Uart, unexpected byte: 0
[2025-05-30 00:17:40] Uart, unexpected byte: 0
[2025-05-30 00:17:40] Uart, unexpected byte: 0
[2025-05-30 00:17:40] UART_RX_DISABLED
[2025-05-30 00:17:40] Scan module initialized
[2025-05-30 00:17:40] Bt: filling scan filters
[2025-05-30 00:17:40] --- Pairing ended, success = 0: Pairing mode off. ---
[2025-05-30 00:17:40] Enabling UART
[2025-05-30 00:17:40] Bt: filling scan filters
[2025-05-30 00:17:40] Conn state: UartLeft(Ready)
[2025-05-30 00:17:40] Resetting left right link! Bidirectional
[2025-05-30 00:17:40] Batteries: 88% 0% (4090 0)
[2025-05-30 00:17:40] Filters matched: left (n/a, ec:a8:8d:e5:7f:36), connectable:1
[2025-05-30 00:17:40] Scan connecting: left (n/a, ec:a8:8d:e5:7f:36)
[2025-05-30 00:17:40] [00:00:05.596,588] <wrn> bt_smp: Unexpected SMP code 0x0b
[2025-05-30 00:17:40] [00:00:05.600,372] <wrn> Bt: Bt security failed: left (n/a, ec:a8:8d:e5:7f:36), level 1, err 9, disconnecting
[2025-05-30 00:17:42]
[2025-05-30 00:17:42] [00:00:05.694,763] <wrn> bt_smp: Unexpected SMP code 0x02
[2025-05-30 00:17:42] [00:00:06.372,070] <inf> Battery: ... Voltage corrected because of charging 4148 -> 4072 (96) -> 4063 (95)
[2025-05-30 00:17:42] [00:00:06.372,161] <inf> Battery:     ... Averaged to 4063 (95)
[2025-05-30 00:17:42] corrected voltage is 4063 95
[2025-05-30 00:17:42] Batteries: 88% 95% (4090 4063)
[2025-05-30 00:17:42] [00:00:07.572,418] <inf> Battery: ... Powered, not charging, not correcting: 4078 (96)
[2025-05-30 00:17:45] [00:00:07.572,448] <inf> Battery:     ... Averaged to 4078 (96)
[2025-05-30 00:17:45] Step called with 95 96 -> 95
[2025-05-30 00:17:45] corrected voltage is 4078 95
[2025-05-30 00:17:45] Batteries: 88% 95% (4090 4078)
[2025-05-30 00:17:45] Batteries: 88% 95% (4089 4078)
[2025-05-30 00:17:45] [00:00:10.944,885] <wrn> bt_hci_core: opcode 0x2013 status 0x3a

@pcooke9 pcooke9 changed the title UHK80 crash when pairing TV UHK80 crash when cancelling pairing request code entry prompt May 30, 2025
@mhantsch
Copy link
Contributor

This is similar to what I experience and described in #999 although in my case the crash happens automatically if I try to pair through the Pop_OS! Bluetooth UI without me actively cancelling the pairing. I don't even get to the pairing code entry.

I can manually pair through commandline calls to bluetoothctl but it is weird and tedious and doesn't always work.

@kareltucek
Copy link
Collaborator

Terribly sorry for so long wait time!

seems weird to me. Shouldn't "Host22" be "Host3"? (bc:7e:8b:d1:ee:19 is the TV) I only have a few BLE devices and one dongle, definitely not 22 hosts:

22 (BLE HID) and 23 (USB) are placeholders for when the device is not present in the UserConfig host connection list. This is expected for newly paired devices.

Eventually, Agent should maintain a "new ble hid device" placeholder just the way it does for a usb connection (which is why you see Host 23 only very rarely - as a result of invalid user config usually).


The ASSERTION FAIL @ WEST_TOPDIR/zephyr/subsys/bluetooth/host/conn.c:1522 line corresponds to an invalid connection being passed to bt unref command.

I have added some more traces, so please @pcooke9 retest with this firmware:

uhk-firmware-14.0.3-bt_debug-v1.6.tar.gz

@pcooke9
Copy link
Author
pcooke9 commented May 30, 2025

22 (BLE HID) and 23 (USB) are placeholders

Ok, that makes sense.

Speaking of long wait times; I'll have to test that tomorrow. It's just about bedtime for me now.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants
0