nRF54L15 - Reflector Crash (BUS FAULT) in Channel Sounding Duration Test (SDK 3.0.2)

We are running a duration test with 10 reflectors and 10 initiators to evaluate the reliability of our system using the nRF54L15 and a project based on the Channel Sounding sample. The original sample project for the reflector part provided by Nordic is configured to reboot after a disconnect event, however, for our application, we require the device not to reboot after disconnect, as this increases power consumption unnecessarily. Instead, we want the reflector to return to a clean slate and be ready for the next connection without a full system reset.

Environment

  • nRF Connect SDK v3.0.2. We are aware there are newer versions available, unfortunately we do not want to upgrade for now due to prior qualification on other parts of the software.
  • Based on sample channel_sounding_ras_reflector with reboot removed

Scenario

Each “gate” (initiator) and each “person” (reflector) perform channel sounding, with reflectors advertising frequently for presence detection. In our duration testing with 10 initiators and 10 reflectors, all in range of each other, we observe that eventually a reflector will crash and reboot. Uptime to failure is between 1 hour and 3 days.

Log Snippet

[Mon Jan 12 09:44:49.398 2026] [00:27:35.711,901] <inf> App_Reflector: CS config creation complete. ID: 0
[Mon Jan 12 09:44:49.398 2026] [00:27:35.911,878] <inf> App_Reflector: CS security enabled.
[Mon Jan 12 09:44:49.398 2026] [00:27:36.161,967] <inf> App_Reflector: CS procedures enabled.
[Mon Jan 12 09:44:55.006 2026] [00:27:41.162,431] <wrn> bt_conn: conn 0x20003f30: not connected
[Mon Jan 12 09:44:55.006 2026] [00:27:41.162,668] <wrn> ras_rrsp: Peer is not subscribed to RAS-CP.
[Mon Jan 12 09:44:55.006 2026] [00:27:41.162,675] <wrn> ras_rrsp: Failed to send indication: -22
[Mon Jan 12 09:44:55.006 2026] [00:27:41.162,715] <inf> App_Reflector: Disconnected (reason 0x13)
                               [00:27:44.222,546] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,900] <err> os: ***** BUS FAULT *****
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,908] <err> os:   Precise data bus error
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,914] <err> os:   BFAR Address: 0x4
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,926] <err> os: r0/a1:  0x20003ba8  r1/a2:  0x00000000  r2/a3:  0x200011dc
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,933] <err> os: r3/a4:  0x00000000 r12/ip:  0xb3199073 r14/lr:  0x00048b83
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,939] <err> os:  xpsr:  0x810002f4
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,944] <err> os: Faulting instruction address (r15/pc): 0x000488dc
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,965] <err> os: >>> ZEPHYR FATAL ERROR 25: Unknown error on CPU 0
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,970] <err> os: Fault during interrupt handling
[Mon Jan 12 09:44:57.233 2026] 
[Mon Jan 12 09:44:57.233 2026] ASSERTION FAIL @ WEST_TOPDIR/zephyr/include/zephyr/spinlock.h:136
[Mon Jan 12 09:44:57.233 2026] [00:27:44.224,995] <err> os: ***** HARD FAULT *****
[Mon Jan 12 09:44:57.233 2026] [00:27:44.225,001] <err> os:   Fault escalation (see below)
[Mon Jan 12 09:44:57.233 2026] [00:27:44.225,007] <err> os: ARCH_EXCEPT with reason 4
[Mon Jan 12 09:44:57.251 2026] 
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,015] <err> os: r0/a1:  0x00000004  r1/a2:  0x00000088  r2/a3:  0x0000000b
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,022] <err> os: r3/a4:  0x00000004 r12/ip:  0x00000036 r14/lr:  0x00048a6d
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,028] <err> os:  xpsr:  0x21000005
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,033] <err> os: Faulting instruction address (r15/pc): 0x0004df00
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,052] <err> os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,057] <err> os: Fault during interrupt handling
[Mon Jan 12 09:44:57.251 2026] 
[Mon Jan 12 09:44:57.251 2026] [00:27:44.225,073] <err> os: Current thread: 0x20006018 (unknown)
[Mon Jan 12 09:44:57.251 2026] [00:27:44.371,403] <err> fatal_error: Resetting systemø*** Booting MCUboot v2.1.0-dev-ae1ee57f3906 ***
[Mon Jan 12 09:44:57.251 2026] *** Using nRF Connect SDK v3.0.2-89ba1294ac9b ***
[Mon Jan 12 09:44:57.251 2026] *** Using Zephyr OS v4.0.99-f791c49f492c ***
[Mon Jan 12 09:44:57.251 2026] I: Starting bootloader

Observations

  • The crash always follows a connection event when the previous connection cycle ended prematurely.
  • Log shows warnings about connection state and peer not being subscribed to RAS-CP. Likely due to the connection already being disconnected.

Questions

  • Has anyone observed stability issues with the reflector application under high frequency connection/disconnection cycles, specifically with SDK 3.0.2 on nRF54L15
  • What steps are necessary to guarantee the reflector’s state is correctly reset on disconnect, without requiring a full system reboot?

Previous post for extra context: https://devzone.nordicsemi.com/f/nordic-q-a/125500/nrf54l15---perform-channel-sounding-while-reflector-advertises

Kind regards,

Arne

Parents
  • Hi Arne

    Thanks, in this debug log here it seems to be in the idle thread and that it overflows, so can you try increasing the CONFIG_IDLE_STACK_SIZE and maybe main stack size in the application to see if that helps.

    Best regards,

    Simon

  • Hi Simon, we updated 10 reflectors with the increased stack sizes: idle thread from default 320 to 2048 and the app_reflector thread from 512 to 2048.

    Unfortunately, 5 of those have already crashed. The one which has a debug connection remains active so we do not have a crash log yet, but I am guessing the same issue remains.

    I also added logging of the stack usage:

    At the start of the reflector the usage is as follows:

    [Wed Jan 28 12:03:45.930 2026] [00:11:25.177,075] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    							   [00:11:59.387,779] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Wed Jan 28 12:04:20.151 2026] [00:11:59.387,887] <inf> os: 0x20004318 (BT RX WQ):  unused 1312  usage 1888 / 3200 (59 %)
    [Wed Jan 28 12:04:20.151 2026] [00:11:59.389,624] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:12:01.139,222] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:04:21.956 2026] [00:12:01.289,162] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:04:21.956 2026] [00:12:01.489,138] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:04:21.956 2026] [00:12:01.739,228] <inf> App_Reflector: CS procedures enabled.
    							   [00:12:06.739,379] <inf> App_Reflector: Disconnected (reason 0x13)
    [Wed Jan 28 12:04:46.204 2026] [00:12:25.192,855] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    [Wed Jan 28 12:05:46.044 2026] [00:13:25.208,631] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    							   [00:13:28.065,417] <inf> App_Reflector: Connected to F5:2F:A9:7C:A8:3E (random) (err 0x00)
    [Wed Jan 28 12:05:48.830 2026] [00:13:28.065,525] <inf> os: 0x20004318 (BT RX WQ):  unused 1312  usage 1888 / 3200 (59 %)
    [Wed Jan 28 12:05:48.830 2026] [00:13:28.067,273] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:13:29.816,861] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:05:50.576 2026] [00:13:29.966,802] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:05:50.576 2026] [00:13:30.166,777] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:05:50.576 2026] [00:13:30.416,867] <inf> App_Reflector: CS procedures enabled.
    							   [00:13:35.417,011] <inf> App_Reflector: Disconnected (reason 0x13)
    							   [00:13:58.890,670] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Wed Jan 28 12:06:19.651 2026] [00:13:58.890,778] <inf> os: 0x20004318 (BT RX WQ):  unused 1312  usage 1888 / 3200 (59 %)
    [Wed Jan 28 12:06:19.651 2026] [00:13:58.892,526] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:14:00.692,111] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:06:21.458 2026] [00:14:00.842,479] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:06:21.458 2026] [00:14:01.042,036] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:06:21.458 2026] [00:14:01.292,129] <inf> App_Reflector: CS procedures enabled.
    							   [00:14:06.292,276] <inf> App_Reflector: Disconnected (reason 0x13)
    [Wed Jan 28 12:06:45.980 2026] [00:14:25.224,476] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    [Wed Jan 28 12:07:46.002 2026] [00:15:25.240,248] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    							   [00:15:55.462,090] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Wed Jan 28 12:08:16.228 2026] [00:15:55.462,197] <inf> os: 0x20004318 (BT RX WQ):  unused 1296  usage 1904 / 3200 (59 %)
    [Wed Jan 28 12:08:16.228 2026] [00:15:55.463,950] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:15:57.213,536] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:08:17.976 2026] [00:15:57.363,478] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:08:17.976 2026] [00:15:57.563,454] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:08:17.976 2026] [00:15:57.813,543] <inf> App_Reflector: CS procedures enabled.
    							   [00:16:02.813,693] <inf> App_Reflector: Disconnected (reason 0x13)
    [Wed Jan 28 12:08:46.018 2026] [00:16:25.256,023] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    [Wed Jan 28 12:09:46.036 2026] [00:17:25.271,800] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    							   [00:17:36.366,967] <inf> App_Reflector: Connected to F5:2F:A9:7C:A8:3E (random) (err 0x00)
    [Wed Jan 28 12:09:57.391 2026] [00:17:36.367,074] <inf> os: 0x20004318 (BT RX WQ):  unused 1296  usage 1904 / 3200 (59 %)
    [Wed Jan 28 12:09:57.391 2026] [00:17:36.368,822] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:17:38.118,439] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:09:58.887 2026] [00:17:38.268,383] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:09:58.887 2026] [00:17:38.468,328] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:09:58.887 2026] [00:17:38.718,417] <inf> App_Reflector: CS procedures enabled.
    							   [00:17:43.718,560] <inf> App_Reflector: Disconnected (reason 0x13)
    							   [00:17:59.072,049] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Wed Jan 28 12:10:19.841 2026] [00:17:59.072,156] <inf> os: 0x20004318 (BT RX WQ):  unused 1296  usage 1904 / 3200 (59 %)
    [Wed Jan 28 12:10:19.841 2026] [00:17:59.073,910] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:18:00.823,519] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:10:21.588 2026] [00:18:00.973,428] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:10:21.588 2026] [00:18:01.173,409] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:10:21.588 2026] [00:18:01.423,499] <inf> App_Reflector: CS procedures enabled.
    							   [00:18:06.424,143] <inf> App_Reflector: Disconnected (reason 0x13)
    [Wed Jan 28 12:10:46.049 2026] [00:18:25.287,575] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    [Wed Jan 28 12:11:46.065 2026] [00:19:25.303,352] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    							   [00:19:55.194,679] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Wed Jan 28 12:12:15.965 2026] [00:19:55.194,786] <inf> os: 0x20004318 (BT RX WQ):  unused 1296  usage 1904 / 3200 (59 %)
    [Wed Jan 28 12:12:15.965 2026] [00:19:55.196,534] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:19:56.946,126] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:12:17.713 2026] [00:19:57.096,066] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:12:17.713 2026] [00:19:57.296,042] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:12:17.713 2026] [00:19:57.546,132] <inf> App_Reflector: CS procedures enabled.
    							   [00:20:02.546,281] <inf> App_Reflector: Disconnected (reason 0x13)
    [Wed Jan 28 12:12:46.081 2026] [00:20:25.319,127] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    [Wed Jan 28 12:13:46.344 2026] [00:21:25.334,904] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    							   [00:21:55.149,097] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Wed Jan 28 12:14:16.153 2026] [00:21:55.149,204] <inf> os: 0x20004318 (BT RX WQ):  unused 1296  usage 1904 / 3200 (59 %)
    [Wed Jan 28 12:14:16.153 2026] [00:21:55.150,956] <inf> os: 0x20002ab8 (App_Reflector):  unused 1656  usage 392 / 2048 (19 %)
    							   [00:21:57.000,512] <inf> App_Reflector: CS capability exchange completed.
    [Wed Jan 28 12:14:17.771 2026] [00:21:57.150,461] <inf> App_Reflector: CS config creation complete. ID: 0
    [Wed Jan 28 12:14:17.771 2026] [00:21:57.350,437] <inf> App_Reflector: CS security enabled.
    [Wed Jan 28 12:14:17.771 2026] [00:21:57.600,527] <inf> App_Reflector: CS procedures enabled.
    							   [00:22:02.600,705] <inf> App_Reflector: Disconnected (reason 0x13)
    [Wed Jan 28 12:14:46.114 2026] [00:22:25.350,680] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)
    [Wed Jan 28 12:15:46.132 2026] [00:23:25.366,456] <inf> os: 0x20002a08 (AppFSM_Thread):  unused 1560  usage 488 / 2048 (23 %)

    After a while there is some increase in stack usage on the reflector thread, but not enough for it to crash so quickly:

    [Thu Jan 29 11:13:45.643 2026] [00:41:17.949,900] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:13:45.643 2026] [00:41:17.951,653] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:41:19.701,227] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:13:47.402 2026] [00:41:19.851,177] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:13:47.402 2026] [00:41:20.051,152] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:13:47.402 2026] [00:41:20.301,270] <inf> App_Reflector: CS procedures enabled.
    							   [00:41:25.301,384] <inf> App_Reflector: Disconnected (reason 0x13)
    [Thu Jan 29 11:14:09.957 2026] [00:41:42.263,161] <inf> os: 0x20002a08 (AppFSM_Thread):	unused 1560	usage 488 / 2048 (23 %)
    							   [00:41:52.817,731] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Thu Jan 29 11:14:20.512 2026] [00:41:52.817,838] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:14:20.512 2026] [00:41:52.819,589] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:41:54.569,170] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:14:22.265 2026] [00:41:54.719,119] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:14:22.265 2026] [00:41:54.919,095] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:14:22.265 2026] [00:41:55.169,185] <inf> App_Reflector: CS procedures enabled.
    							   [00:42:00.169,335] <inf> App_Reflector: Disconnected (reason 0x13)
    [Thu Jan 29 11:15:09.977 2026] [00:42:42.278,936] <inf> os: 0x20002a08 (AppFSM_Thread):	unused 1560	usage 488 / 2048 (23 %)
    							   [00:42:52.882,142] <inf> App_Reflector: Connected to F9:02:C8:90:72:A6 (random) (err 0x00)
    [Thu Jan 29 11:15:20.583 2026] [00:42:52.882,249] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:15:20.583 2026] [00:42:52.883,641] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:42:54.633,598] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:15:22.336 2026] [00:42:54.783,534] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:15:22.336 2026] [00:42:54.983,516] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:15:22.336 2026] [00:42:55.233,606] <inf> App_Reflector: CS procedures enabled.
    							   [00:43:00.233,777] <inf> App_Reflector: Disconnected (reason 0x13)
    							   [00:43:25.931,367] <inf> App_Reflector: Connected to F5:2F:A9:7C:A8:3E (random) (err 0x00)
    [Thu Jan 29 11:15:53.631 2026] [00:43:25.931,474] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:15:53.631 2026] [00:43:25.933,259] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:43:27.682,801] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:15:55.382 2026] [00:43:27.832,750] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:15:55.382 2026] [00:43:28.032,726] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:15:55.383 2026] [00:43:28.282,815] <inf> App_Reflector: CS procedures enabled.
    							   [00:43:33.282,958] <inf> App_Reflector: Disconnected (reason 0x13)
    [Thu Jan 29 11:16:09.994 2026] [00:43:42.294,713] <inf> os: 0x20002a08 (AppFSM_Thread):	unused 1560	usage 488 / 2048 (23 %)
    							   [00:43:52.749,196] <inf> App_Reflector: Connected to F4:6C:46:3F:D2:03 (random) (err 0x00)
    [Thu Jan 29 11:16:20.450 2026] [00:43:52.749,303] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:16:20.450 2026] [00:43:52.751,049] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:43:54.500,643] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:16:22.203 2026] [00:43:54.650,584] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:16:22.203 2026] [00:43:54.850,558] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:16:22.203 2026] [00:43:55.100,649] <inf> App_Reflector: CS procedures enabled.
    							   [00:44:00.100,799] <inf> App_Reflector: Disconnected (reason 0x13)
    							   [00:44:24.759,684] <inf> App_Reflector: Connected to F2:F0:F2:89:32:28 (random) (err 0x00)
    [Thu Jan 29 11:16:52.462 2026] [00:44:24.759,791] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:16:52.462 2026] [00:44:24.761,538] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:44:26.511,107] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:16:54.214 2026] [00:44:26.661,055] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:16:54.214 2026] [00:44:26.861,029] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:16:54.214 2026] [00:44:27.111,117] <inf> App_Reflector: CS procedures enabled.
    							   [00:44:32.111,229] <inf> App_Reflector: Disconnected (reason 0x13)
    [Thu Jan 29 11:17:10.009 2026] [00:44:42.310,488] <inf> os: 0x20002a08 (AppFSM_Thread):	unused 1560	usage 488 / 2048 (23 %)
    							   [00:44:52.819,238] <inf> App_Reflector: Connected to F9:02:C8:90:72:A6 (random) (err 0x00)
    [Thu Jan 29 11:17:20.529 2026] [00:44:52.819,345] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Thu Jan 29 11:17:20.529 2026] [00:44:52.821,120] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   [00:44:54.570,694] <inf> App_Reflector: CS capability exchange completed.
    [Thu Jan 29 11:17:22.280 2026] [00:44:54.770,636] <inf> App_Reflector: CS config creation complete. ID: 0
    [Thu Jan 29 11:17:22.280 2026] [00:44:54.970,613] <inf> App_Reflector: CS security enabled.
    [Thu Jan 29 11:17:22.280 2026] [00:44:55.220,703] <inf> App_Reflector: CS procedures enabled.
    							   [00:45:00.220,884] <inf> App_Reflector: Disconnected (reason 0x13)

    Looks like the stack usage was initially 424 / 512 or  ~83% and is now 424 / 2048 or ~21%. 

  • Small update: the reflector with the debug connection has crashed now as well, with the same exact error.

    [Fri Jan 30 08:21:59.602 2026] <inf> App_Reflector: Connected to F5:2F:A9:7C:A8:3E (random) (err 0x00)
    [Fri Jan 30 08:21:59.602 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:21:59.602 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    [00:21:02.676,928] <inf> App_Reflector: CS capability exchange completed.
    [Fri Jan 30 08:22:02.178 2026] <inf> App_Reflector: CS config creation complete. ID: 0
    [Fri Jan 30 08:22:02.178 2026] <inf> App_Reflector: CS security enabled.
    [Fri Jan 30 08:22:02.178 2026] <inf> App_Reflector: CS procedures enabled.[00:21:08.277,077] <inf> App_Reflector: Disconnected (reason 0x13)
    							   <inf> App_Reflector: Connected to D2:88:A1:41:F5:1C (random) (err 0x00)
    [Fri Jan 30 08:22:22.597 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:22:22.597 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   <inf> App_Reflector: CS capability exchange completed.
    [Fri Jan 30 08:22:24.447 2026] <inf> App_Reflector: CS config creation complete. ID: 0
    [Fri Jan 30 08:22:24.447 2026] <inf> App_Reflector: CS security enabled.
    [Fri Jan 30 08:22:24.447 2026] <inf> App_Reflector: CS procedures enabled.
    							   <inf> App_Reflector: Disconnected (reason 0x13)
    [Fri Jan 30 08:22:32.344 2026] <inf> os: 0x20002a08 (AppFSM_Thread):	unused 1560	usage 488 / 2048 (23 %)
    [Fri Jan 30 08:22:32.344 2026] <inf> App_Reflector: Connected to D2:88:A1:41:F5:1C (random) (err 0x00)
    [Fri Jan 30 08:22:32.344 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:22:32.344 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    [Fri Jan 30 08:22:34.831 2026] <inf> App_Reflector: CS capability exchange completed.
    [Fri Jan 30 08:22:34.857 2026] <inf> App_Reflector: CS config creation complete. ID: 0
    [Fri Jan 30 08:22:34.857 2026] <inf> App_Reflector: CS security enabled.
    [Fri Jan 30 08:22:34.857 2026] <inf> App_Reflector: CS procedures enabled.
    							   <wrn> bt_conn: conn 0x20004440: not connected
    [Fri Jan 30 08:22:40.644 2026] <wrn> ras_rrsp: Peer is not subscribed to RAS-CP.
    [Fri Jan 30 08:22:40.644 2026] <wrn> ras_rrsp: Failed to send indication: -22
    [Fri Jan 30 08:22:40.644 2026] <inf> App_Reflector: Disconnected (reason 0x13)
    [Fri Jan 30 08:22:40.644 2026] <inf> App_Reflector: Connected to D2:1A:7A:60:B5:FF (random) (err 0x00)
    [Fri Jan 30 08:22:40.644 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:22:40.644 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    [Fri Jan 30 08:22:40.644 2026] <err> os: ***** BUS FAULT *****
    [Fri Jan 30 08:22:40.644 2026] <err> os:   Precise data bus error
    [Fri Jan 30 08:22:40.644 2026] <err> os:   BFAR Address: 0x4
    [Fri Jan 30 08:22:40.644 2026] ASSERTION FAIL @ WEST_TOPDIR/zephyr/include/zephyr/spinlock.h:136
    [Fri Jan 30 08:22:40.644 2026] <err> os: ***** HARD FAULT *****
    [Fri Jan 30 08:22:40.644 2026] <err> os:   Fault escalation (see below)
    [Fri Jan 30 08:22:40.644 2026] <err> os: ARCH_EXCEPT with reason 4
    [Fri Jan 30 08:22:40.655 2026] <err> os: r0/a1:  0x00000004  r1/a2:  0x00000088  r2/a3:  0x0000000b
    [Fri Jan 30 08:22:40.655 2026] <err> os: r3/a4:  0x00000004 r12/ip:  0x0000003a r14/lr:  0x0004bee9
    [Fri Jan 30 08:22:40.655 2026] <err> os:  xpsr:  0x21000005
    [Fri Jan 30 08:22:40.655 2026] <err> os: Faulting instruction address (r15/pc): 0x00051a10
    [Fri Jan 30 08:22:40.655 2026] <err> os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
    [Fri Jan 30 08:22:40.655 2026] <err> os: Fault during interrupt handling
    [Fri Jan 30 08:22:40.655 2026] <err> os: Current thread: 0x20006558 (idle)
    [Fri Jan 30 08:22:40.655 2026] <err> fatal_error: Resetting system

Reply
  • Small update: the reflector with the debug connection has crashed now as well, with the same exact error.

    [Fri Jan 30 08:21:59.602 2026] <inf> App_Reflector: Connected to F5:2F:A9:7C:A8:3E (random) (err 0x00)
    [Fri Jan 30 08:21:59.602 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:21:59.602 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    [00:21:02.676,928] <inf> App_Reflector: CS capability exchange completed.
    [Fri Jan 30 08:22:02.178 2026] <inf> App_Reflector: CS config creation complete. ID: 0
    [Fri Jan 30 08:22:02.178 2026] <inf> App_Reflector: CS security enabled.
    [Fri Jan 30 08:22:02.178 2026] <inf> App_Reflector: CS procedures enabled.[00:21:08.277,077] <inf> App_Reflector: Disconnected (reason 0x13)
    							   <inf> App_Reflector: Connected to D2:88:A1:41:F5:1C (random) (err 0x00)
    [Fri Jan 30 08:22:22.597 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:22:22.597 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    							   <inf> App_Reflector: CS capability exchange completed.
    [Fri Jan 30 08:22:24.447 2026] <inf> App_Reflector: CS config creation complete. ID: 0
    [Fri Jan 30 08:22:24.447 2026] <inf> App_Reflector: CS security enabled.
    [Fri Jan 30 08:22:24.447 2026] <inf> App_Reflector: CS procedures enabled.
    							   <inf> App_Reflector: Disconnected (reason 0x13)
    [Fri Jan 30 08:22:32.344 2026] <inf> os: 0x20002a08 (AppFSM_Thread):	unused 1560	usage 488 / 2048 (23 %)
    [Fri Jan 30 08:22:32.344 2026] <inf> App_Reflector: Connected to D2:88:A1:41:F5:1C (random) (err 0x00)
    [Fri Jan 30 08:22:32.344 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:22:32.344 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    [Fri Jan 30 08:22:34.831 2026] <inf> App_Reflector: CS capability exchange completed.
    [Fri Jan 30 08:22:34.857 2026] <inf> App_Reflector: CS config creation complete. ID: 0
    [Fri Jan 30 08:22:34.857 2026] <inf> App_Reflector: CS security enabled.
    [Fri Jan 30 08:22:34.857 2026] <inf> App_Reflector: CS procedures enabled.
    							   <wrn> bt_conn: conn 0x20004440: not connected
    [Fri Jan 30 08:22:40.644 2026] <wrn> ras_rrsp: Peer is not subscribed to RAS-CP.
    [Fri Jan 30 08:22:40.644 2026] <wrn> ras_rrsp: Failed to send indication: -22
    [Fri Jan 30 08:22:40.644 2026] <inf> App_Reflector: Disconnected (reason 0x13)
    [Fri Jan 30 08:22:40.644 2026] <inf> App_Reflector: Connected to D2:1A:7A:60:B5:FF (random) (err 0x00)
    [Fri Jan 30 08:22:40.644 2026] <inf> os: 0x20004318 (BT RX WQ):	unused 1296	usage 1904 / 3200 (59 %)
    [Fri Jan 30 08:22:40.644 2026] <inf> os: 0x20002ab8 (App_Reflector):	unused 1624	usage 424 / 2048 (20 %)
    [Fri Jan 30 08:22:40.644 2026] <err> os: ***** BUS FAULT *****
    [Fri Jan 30 08:22:40.644 2026] <err> os:   Precise data bus error
    [Fri Jan 30 08:22:40.644 2026] <err> os:   BFAR Address: 0x4
    [Fri Jan 30 08:22:40.644 2026] ASSERTION FAIL @ WEST_TOPDIR/zephyr/include/zephyr/spinlock.h:136
    [Fri Jan 30 08:22:40.644 2026] <err> os: ***** HARD FAULT *****
    [Fri Jan 30 08:22:40.644 2026] <err> os:   Fault escalation (see below)
    [Fri Jan 30 08:22:40.644 2026] <err> os: ARCH_EXCEPT with reason 4
    [Fri Jan 30 08:22:40.655 2026] <err> os: r0/a1:  0x00000004  r1/a2:  0x00000088  r2/a3:  0x0000000b
    [Fri Jan 30 08:22:40.655 2026] <err> os: r3/a4:  0x00000004 r12/ip:  0x0000003a r14/lr:  0x0004bee9
    [Fri Jan 30 08:22:40.655 2026] <err> os:  xpsr:  0x21000005
    [Fri Jan 30 08:22:40.655 2026] <err> os: Faulting instruction address (r15/pc): 0x00051a10
    [Fri Jan 30 08:22:40.655 2026] <err> os: >>> ZEPHYR FATAL ERROR 4: Kernel panic on CPU 0
    [Fri Jan 30 08:22:40.655 2026] <err> os: Fault during interrupt handling
    [Fri Jan 30 08:22:40.655 2026] <err> os: Current thread: 0x20006558 (idle)
    [Fri Jan 30 08:22:40.655 2026] <err> fatal_error: Resetting system

Children
No Data
Related