Debugging 'sdu_ref_us not from consecutive frames' Issue with Samsung S23

[JimmyOu]

Hi,

I am currently encountering an issue with the error message 'sdu_ref_us not from consecutive frames' in the following scenario:

• Broadcasting Source: A Samsung S23 device starts broadcasting audio.
• Two ADKs are involved:
  • ADK#1: Syncs audio from the beginning and operates without any issues.
  • ADK#2: Begins syncing approximately 11 minutes after broadcasting starts and reports numerous 'sdu_ref_us not from consecutive frames' messages.

This behavior persists even after rebooting the devices.

I would greatly appreciate your guidance in understanding why this issue occurs with ADK#2.

Thank you in advance for your time and assistance.

Please let me know if additional information or logs would be helpful.

Attachment: Logs from ADK#2 for reference.

s23_issue.log

Environments

• Broadcast source: Samsung S23
• Broadcast sink:
  • Board: nRF5340 Audio DK
  • SDK: nRF Connect SDK v2.9.0
  • Application: nrf/applications/nrf5340_audio

Best regards,

Jimmy

[dejans]

Hi,

Can you send log from device ADK#1?

Have you made any changes in the application on both boards?

Have you verified the behavior with any other phone? Are there any differences in the output?

Best regards,
Dejan

[JimmyOu]

Hi dejans,

Thank you for your prompt reply and assistance.

Yes, I changed the settings to BIS. Below are configurations I added.

CONFIG_TRANSPORT_BIS=y
CONFIG_BT_AUDIO_BROADCAST_NAME="JimmyS23"
CONFIG_BT_AUDIO_BROADCAST_ENCRYPTED=y
CONFIG_BT_AUDIO_BROADCAST_ENCRYPTION_KEY="1234"

Attachments are ADK#1 and ADK#2 logs.

Fullscreen s23_adk_1_no_issue.log Download

[2025-01-28 02:15:51] picocom v3.1
[2025-01-28 02:15:51] 
[2025-01-28 02:15:51] port is        : /dev/ttyACM4
[2025-01-28 02:15:51] flowcontrol    : none
[2025-01-28 02:15:51] baudrate is    : 115200
[2025-01-28 02:15:51] parity is      : none
[2025-01-28 02:15:51] databits are   : 8
[2025-01-28 02:15:51] stopbits are   : 1
[2025-01-28 02:15:51] escape is      : C-a
[2025-01-28 02:15:51] local echo is  : no
[2025-01-28 02:15:51] noinit is      : no
[2025-01-28 02:15:51] noreset is     : no
[2025-01-28 02:15:51] hangup is      : no
[2025-01-28 02:15:51] nolock is      : no
[2025-01-28 02:15:51] send_cmd is    : sz -vv
[2025-01-28 02:15:51] receive_cmd is : rz -vv -E
[2025-01-28 02:15:51] imap is        : 
[2025-01-28 02:15:51] omap is        : 
[2025-01-28 02:15:51] emap is        : crcrlf,delbs,
[2025-01-28 02:15:51] logfile is     : none
[2025-01-28 02:15:51] initstring     : none
[2025-01-28 02:15:51] exit_after is  : not set
[2025-01-28 02:15:51] exit is        : no
[2025-01-28 02:15:51] 
[2025-01-28 02:15:51] Type [C-a] [C-h] to see available commands
[2025-01-28 02:15:51] Terminal ready
[2025-01-28 02:15:52] HL [00:05:12.860,107] <inf> audio_datapath: Drft comp state: OFFSET
[2025-01-28 02:15:52] HL [00:05:13.360,473] <inf> audio_datapath: Drft comp state: LOCKED
[2025-01-28 02:15:52] HL [00:05:13.370,056] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:52] HL [00:05:13.462,829] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:15:52] HL [00:05:13.462,829] <wrn> audio_datapath: Requested presentation delay out of range: pres_adj_us=62328
[2025-01-28 02:15:52] HL [00:05:13.611,419] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:15:52] HL [00:05:13.612,823] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:52] HL [00:05:13.731,445] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:15:52] HL [00:05:13.880,065] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:15:52] HL [00:05:13.881,408] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:52] HL [00:05:14.000,061] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:15:52] HL [00:05:14.122,863] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:15:52] HL [00:05:14.150,085] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:52] HL [00:05:14.242,858] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:15:52] HL [00:05:14.391,448] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:15:52] HL [00:05:14.392,883] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:52] HL [00:05:14.511,444] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:15:52] HL [00:05:14.660,095] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:15:52] HL [00:05:14.661,468] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:53] HL [00:05:14.780,090] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:15:54] HL [00:05:14.902,893] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:15:54] HL [00:05:14.930,114] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:15:54] HL [00:05:15.022,888] <inf> audio_datapath: Pres comp state: LOCKED
[2025-01-28 02:15:55] *** Booting nRF Connect SDK v2.9.0-7787b2649840 ***
[2025-01-28 02:15:55] *** Using Zephyr OS v3.7.99-1f8f3dc29142 ***
[2025-01-28 02:15:55] HL [00:00:00.268,737] <inf> board_version: Compatible board/HW version found: 1.0.0
[2025-01-28 02:15:55] HL [00:00:00.304,473] <inf> sd_card: SD card volume size: 7969177600 B
[2025-01-28 02:15:55] HL [00:00:00.309,143] <inf> fw_info: 
[2025-01-28 02:15:55] 	 nRF5340 Audio nRF5340 Audio DK cpuapp 			    
[2025-01-28 02:15:55] 	 NCS base version: 2.9.0 			    
[2025-01-28 02:15:55] 	 Cmake run : Sun Jan 26 23:20:21 2025
[2025-01-28 02:15:55] HL [00:00:00.309,173] <inf> fw_info: ------- DEBUG BUILD -------
[2025-01-28 02:15:55] HL [00:00:00.309,173] <inf> fw_info: HEADSET left device
[2025-01-28 02:15:55] HL [00:00:00.375,183] <inf> bt_mgmt_ctlr_cfg: Controller: SoftDevice: Version 6.0 (0x0e), Revision 8299
[2025-01-28 02:15:55] HL [00:00:00.375,366] <inf> bt_mgmt: Local identity addr: E6:4A:07:D9:9C:6C (random)
[2025-01-28 02:15:55] HL [00:00:00.405,456] <inf> bt_mgmt_scan: Local addr: E6:4A:07:D9:9C:6C (random). May time out. Updates not printed
[2025-01-28 02:15:55] HL [00:00:00.405,456] <inf> bt_mgmt_scan: Scanning successfully started
[2025-01-28 02:15:57] HL [00:00:00.796,600] <inf> bt_mgmt_scan: PA synced to name: JimmyS23, id: 0x4d5650, addr: 00:CF:D7:DC:D3:74 (random)
[2025-01-28 02:15:57] HL [00:00:01.336,853] <inf> broadcast_sink: Syncing to broadcast stream index 0
[2025-01-28 02:15:57] HL [00:00:01.337,036] <inf> main: Presentation delay 40000 us is set
[2025-01-28 02:15:58] HL [00:00:03.528,808] <inf> broadcast_sink: Stream index 0 started
[2025-01-28 02:15:58] HL [00:00:03.528,808] <inf> broadcast_sink: Codec config for LC3:
[2025-01-28 02:15:58] HL [00:00:03.528,808] <inf> broadcast_sink: 	Frequency: 48000 Hz
[2025-01-28 02:15:58] HL [00:00:03.528,808] <inf> broadcast_sink: 	Frame Duration: 10000 us
[2025-01-28 02:15:58] HL [00:00:03.528,839] <inf> broadcast_sink: 	Octets per frame: 120 (96000 kbps)
[2025-01-28 02:15:58] HL [00:00:03.528,839] <inf> broadcast_sink: 	Frames per SDU: 1
[2025-01-28 02:15:58] HL [00:00:03.528,839] <inf> broadcast_sink: 	Channel allocation: 0x1
[2025-01-28 02:15:58] HL [00:00:03.531,311] <wrn> le_audio_rx: Not in streaming state (1), thrown 1 packet(s)
[2025-01-28 02:15:58] HL [00:00:03.567,718] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:58] HL [00:00:03.568,695] <wrn> audio_datapath: Data received, total under-runs: 27
[2025-01-28 02:15:59] HL [00:00:03.667,724] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:03.668,731] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:03.768,707] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:03.769,714] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:03.869,720] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:03.870,727] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:03.970,733] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:03.971,740] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:04.071,746] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:04.072,723] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:04.172,729] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:04.173,736] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:04.273,742] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:04.274,749] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:04.374,755] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:04.375,732] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:04.475,769] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:04.476,745] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:15:59] HL [00:00:04.576,751] <inf> audio_datapath: Drft comp state: INIT
[2025-01-28 02:15:59] HL [00:00:04.577,758] <inf> audio_datapath: Drft comp state: CALIB
[2025-01-28 02:16:00] HL [00:00:04.677,734] <inf> audio_datapath: Drft comp state: OFFSET
[2025-01-28 02:16:00] HL [00:00:05.121,337] <wrn> audio_datapath: Data received, total under-runs: 40
[2025-01-28 02:16:00] HL [00:00:05.177,337] <inf> audio_datapath: Drft comp state: LOCKED
[2025-01-28 02:16:00] HL [00:00:05.186,859] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:16:00] HL [00:00:05.279,632] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:16:00] HL [00:00:05.428,222] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:16:01] HL [00:00:05.429,656] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:16:01] HL [00:00:05.548,248] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:16:01] HL [00:00:05.696,868] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:16:01] HL [00:00:05.698,242] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:16:02] HL [00:00:05.816,894] <inf> audio_datapath: Pres comp state: WAIT
[2025-01-28 02:16:02] HL [00:00:05.939,666] <inf> audio_datapath: Pres comp state: INIT
[2025-01-28 02:16:02] HL [00:00:05.966,918] <inf> audio_datapath: Pres comp state: MEAS
[2025-01-28 02:16:02] HL [00:00:06.059,692] <inf> audio_datapath: Pres comp state: LOCKED
[2025-01-28 02:28:46] 
[2025-01-28 02:28:46] Terminating...
[2025-01-28 02:28:46] Thanks for using picocom

s23_adk_2_issue.log

I only verified the issue using two different Samsung S23 devices, and unfortunately, the results remain the same.

Thanks again for your help.

Best regards,

Jimmy

[dejans]

Hi Jimmy,

What happens if you remove broadcast encryption? Do you get different result?

Best regards,
Dejan

[JimmyOu]

Hi Dejans,

I tried to remove broadcast encryption and the result is the same.

s23_adk_2_issue_no_encryption.log

Best regards,

Jimmy

[dejans]

Hi Jimmy,

I have reported this issue internally and we will look into it. I expect to get back to you by the end of this week.

Best regards,
Dejan

[dejans]

Hi Jimmy,

We are still looking into this issue. I expect to get back to you by the end of the next week.

Best regards,
Dejan

[JimmyOu]

Hi Dejans,

Thanks for your update. I really appreciate that.

Best regards,

Jimmy