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

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

Parents
  • 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

  • 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.

    [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

  • Hi Jimmy,

    We are still looking into this. I will provide status update by the end of next week.
    Thank you for your patience.

    Best regards,
    Dejan

  • Hi Jimmy,

    We are still looking into this. Unfortunately, it might take longer than expected. I will get back to you with the status update by the end of next week.

    Thank you for your patience and understanding.

    Best regards,
    Dejan

  • Hi Jimmy,

    I am sorry for a delayed reply. There is some progress on this issue, but further investigation is needed. I will get back to you by the end of this week.

    Best regards,
    Dejan

  • Hi Jimmy, 

    It seems that the issue with the drift is coming from the phone, not from controller. Do you have any other phone (except for S23 and S24) to test with?

    Best regards,
    Dejan

  • Hi Dejans,

    Thanks for your feedback.

    Unfortunately, I don't have another phone that supports Auracast.

    I will find one to test if possible.

    Thanks again for your help.

    Best regards,

    Jimmy

Reply Children
Related