Sonos output only 20 seconds

Hi

I have new setup with core 1.7 build 528 and controller in Windows 10 and couple of Android 10 devices.
Core is running in Centos 7.* and kernel 4.4*
I can add my local files in NAS and connect to Tidal.
But when playing anything to any of my 3 different Sonos play stops after about 25 seconds with notice “roon lost control of audio device”. Playing continues if I click play button again but same happens after 20 sec.

All devices are in same subnet and core, one of Sonos and Windows are wired and rest in wifi. I have added ports 9003/udp 9100-9200/tcp in linux firewall and also in Windows roon.exe and raatserver are allowed to public and private.
Rebooting devices has no effect.
Only when playing to windows pc speakers it works normally.

This is from core log file when this happens:

03/30 15:44:25 Trace: [Työhuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 2:11/5:42] Fall From Grace - Paradise Lost
03/30 15:44:25 Trace: [analysis] analysis completed in 17441ms for 201s of MP3 44.1kHz audio. loudness=-27.1400225964491LUFS dbTP=-2.38429981712149 noisestartms=598 noiseendms=199342 isdts=False mqa=0 for track 4435506 url=/nassi/musat/Suomi/Popeda/Kans´an Popeda/12.Viisas Ihminen.mp3
03/30 15:44:25 Trace: [library] finished with 1 dirty tracks 1 dirty albums 3 dirty performers 1 dirty works 1 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 1 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 7 changed objects
03/30 15:44:25 Trace: [dbperf] flush 4495 bytes, 2 ops in 2 ms (cumulative 1322442 bytes, 562 ops in 11822 ms)
03/30 15:44:25 Trace: [library] endmutation in 11ms
03/30 15:44:30 Info: [stats] 3906mb Virtual, 890mb Physical, 352mb Managed, 0 Handles, 75 Threads
03/30 15:44:30 Trace: [Työhuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 2:16/5:42] Fall From Grace - Paradise Lost
03/30 15:44:30 Trace: [Työhuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 2:16/5:42] Fall From Grace - Paradise Lost
03/30 15:44:35 Trace: [Työhuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 2:21/5:42] Fall From Grace - Paradise Lost
03/30 15:44:39 Trace: [Työhuone] [zoneplayer/sonos] startstream timeout expired, state is: WaitForStatus
03/30 15:44:39 Trace: [endpoint/sonos/uuid:RINCON_000E58F0774001400] check zone coordinator, device coordinator: RINCON_000E58F0774001400, roon coordinator: RINCON_000E58F0774001400
03/30 15:44:39 Trace: [client/sonos] timers stopped
03/30 15:44:39 Debug: [smc] [zoneplayer:5] Created domain
03/30 15:44:39 Debug: [smc] [zoneplayer:5] Removing ordinal 12
03/30 15:44:39 Warn: Track Stopped Due to LostEndpoint
03/30 15:44:39 Debug: [smc] [zoneplayer:5] Destroyed domain
03/30 15:44:39 Info: [zone Työhuone] OnPlayFeedback StoppedLostEndpoint
03/30 15:44:39 Trace: [zone Työhuone] Suspend
03/30 15:44:39 Info: [zone Työhuone] OnPlayFeedback Stopped
03/30 15:44:39 Trace: [Työhuone] [zoneplayer/sonos] endstream, zp state: WaitForStatus
03/30 15:44:39 Trace: [Työhuone] [zoneplayer/sonos] State transition WaitForStatus => Disconnected
03/30 15:44:39 Trace: [Työhuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PAUSED @ 2:25/5:42] Fall From Grace - Paradise Lost
03/30 15:44:39 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
03/30 15:44:39 Info: [audio/env] [zoneplayer] All streams were disposed
03/30 15:44:39 Trace: [Työhuone] [zoneplayer/sonos] endstream, zp state: Disconnected
03/30 15:44:39 Trace: [Työhuone] [zoneplayer/sonos] got http request while zoneplayer was in non-playing state, closing connnection
03/30 15:44:39 Trace: [Työhuone] [zoneplayer/sonos] transaction canceled, isplaying: False, did stream end: False, tx path: /cfa23a580caa4ed19029d730988bbe8d/Rooncedbb46650d04d8cbe1bf0ac418299d3.flac, stream path: /cfa23a580caa4ed19029d730988bbe8d/Rooncedbb46650d04d8cbe1bf0ac418299d3.flac, method: Get
03/30 15:44:40 Trace: [Työhuone] [zoneplayer/sonos] pausestream
03/30 15:44:40 Trace: [Työhuone] [zoneplayer/sonos] State transition Disconnected => Paused
03/30 15:44:40 Trace: [client/sonos] pause request
03/30 15:44:40 Trace: [client/sonos] pause response
03/30 15:44:40 Trace: [Työhuone] [zoneplayer/sonos] request matches stream, current path: /cfa23a580caa4ed19029d730988bbe8d/Rooncedbb46650d04d8cbe1bf0ac418299d3.flac, next path: /94783cb96c50429892a775c58dc10bdf/Roon0.flac
03/30 15:44:40 Trace: [Työhuone] [zoneplayer/sonos] reached end of stream, closing connection

Hello @Pasi_V. Do you by any chance have your Sonos system configured to use SonosNet (I think it’s also known as “wired” or “Boost” mode)? I had similar problems using Roon with Sonos devices configured in that mode. Having switched the Sonos system over to using “wireless” aka “Standard” mode, Roon has been able to stream to my Sonos devices with very few problems.

Hi, my Sonos is connected to my normal WIFI. I am not using Sonos internal network nor bridge.
Controller(or zp90) has physical ethernet cable and Play’s are in normal wifi

Hi @Pasi_V,

In the log file snippet you posted, it looks like you’re playing a TIDAL stream. Can you confirm if this issue affects both TIDAL and NAS content? Does the TIDAL web player (listen.tidal.com) work as expected on your end?

What is your network setup like? What is your model/manufacturer of your router? Are you Sonos devices connected directly to the router or to an access point/range extender?

same issue if playing from Tidal or NAS.
My wifi access point is Cisco Aironet 1700 series and it is only working as access point. All “intelligent” stuff is happening in pfSense.(DHCP, bind DNS, LAN/WAN firewall…)
All physically connected devices are connected with Zyxel nonconfigurable switches. All wireles devices are connected to Cisco.

This setup has not changed in any way in last few years and Tidal and Spotify works fine with Sonos using native sonos app or directly from Tidal and Spotify.

Only new component is Roon server runing in Centos.

Hi @Pasi_V,

If you try temporarily hosting your Roon Core on the Windows 10 controller and have the controller connected using the same Ethernet cable, does the same behavior occur? That way we will know if this is a Core issue related to CentOS or if something on the network changed.

Hi, I just tested and issue seems to be related to Sonos.
I changed core to Windows and all Sonos skpeakers were found and enabled, but when tryin to play it fails again “roon los connection to device” error.
I tried to play Nvidia Shield Chromecast and that works fine on both cores.

Here is log from Windows:

--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    Output OutputType=Sonos Quality=Lossless SubType= Model=CONNECT
------------------------------------------------------------
03/30 22:47:13 Trace: [client/sonos] play response
03/30 22:47:13 Trace: [Olohuone] [zoneplayer/sonos] groupandplay end
03/30 22:47:13 Trace: [Olohuone] [zoneplayer/sonos] request matches stream, current path: /6ee868c527d3424faf9a2fc6a1ced15c/Roona8ee294fd0c64f17b44d5349201489d7.flac, next path: 
03/30 22:47:13 Trace: [client/sonos] av_status
03/30 22:47:13 Trace: [Olohuone] [zoneplayer/sonos] state from device: STOPPED
03/30 22:47:13 Trace: [Olohuone] [zoneplayer/sonos]  zoneplayer state: WaitForStatus
03/30 22:47:13 Trace: [client/sonos] av_status
03/30 22:47:13 Trace: [Olohuone] [zoneplayer/sonos] state from device: TRANSITIONING
03/30 22:47:13 Trace: [Olohuone] [zoneplayer/sonos]  zoneplayer state: WaitForStatus
03/30 22:47:19 Trace: [Olohuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:42/5:45] You Could Be Mine - Guns N' Roses
03/30 22:47:19 Trace: [Olohuone] [zoneplayer/sonos] position timer, stopping stream due to 0 positions
03/30 22:47:19 Trace: [Olohuone] [zoneplayer/sonos] State transition WaitForStatus => Disconnected
03/30 22:47:19 Trace: [endpoint/sonos/uuid:RINCON_000E58A1C6C801400] check zone coordinator, device coordinator: RINCON_000E58A1C6C801400, roon coordinator: RINCON_000E58A1C6C801400
03/30 22:47:19 Trace: [client/sonos] timers stopped
03/30 22:47:19 Warn: Track Stopped Due to LostEndpoint
03/30 22:47:19 Info: [zone Olohuone] OnPlayFeedback StoppedLostEndpoint
03/30 22:47:19 Trace: [zone Olohuone] Suspend
03/30 22:47:19 Info: [zone Olohuone] OnPlayFeedback Stopped
03/30 22:47:19 Trace: [Olohuone] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PAUSED @ 0:42/5:45] You Could Be Mine - Guns N' Roses
03/30 22:47:19 Trace: [roonapi] [apiclient 10.10.10.33:59330] CONTINUE Changed {"zones_changed":[{"zone_id":"1601ac5f898d7ab17e7a0336520f9caf23df","display_name":"Olohuone","outputs":[{"output_id":"1701ac5f898d7ab17e7a0336520f9caf23df","zone_id":"1601ac5f898d7ab17e7a0336520f9caf23df","can_group_with_output_ids":["17010a5ddd0dbe1f38d5b653cd6d895e3903","1701ac5f898d7ab17e7a0336520f9caf23df","1701d59920df5c56fc0333609187b2eba203"],"display_name":"Olohuone","volume":{"type":"number","min":0,"max":100,"value":100,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":[{"control_key":"1","display_name":"CONNECT (Olohuone)","supports_standby":false,"status":"indeterminate"}]}],"state":"paused","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":303,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":42,"length":345,"one_line":{"line1":"You Could Be Mine - Guns N' Roses"},"two_line":{"line1":"You Could Be Mine","line2":"Guns N' Roses"},"three_line":{"line1":"You Could Be Mine","line2":"Guns N' Roses","line3":"Greatest Hits"},"image_key":"03d6c3c666abe96829a300b8218decb4","artist_image_keys":["f961c6e8cc48d74f4cc7a84c8e2f461e"]}}]}
03/30 22:47:19 Info: Alert: Transport: playback_error_endpoint_lost
03/30 22:47:19 Info: Alert: Transport: playback_error_endpoint_lost
03/30 22:47:19 Trace: [Olohuone] [zoneplayer/sonos] endstream, zp state: Disconnected
03/30 22:47:19 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
03/30 22:47:19 Trace: [Olohuone] [zoneplayer/sonos] endstream, zp state: Disconnected
03/30 22:47:19 Info: [audio/env] [zoneplayer] All streams were disposed
03/30 22:47:22 Trace: [endpoint/sonos/uuid:RINCON_000E58F0774001400] zone update: <ZoneGroupState><ZoneGroups><ZoneGroup Coordinator="RINCON_000E58F0774001400" ID="RINCON_000E58F0774001400:11"><ZoneGroupMember UUID="RINCON_000E58F0774001400" Location="http://10.10.10.21:1400/xml/device_description.xml" ZoneName="Työhuone" Icon="x-rincon-roomicon:library" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="226" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="1" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2422" BehindWifiExtender="0" WifiEnabled="1" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" IdleState="1" MoreInfo=""/></ZoneGroup><ZoneGroup Coordinator="RINCON_5CAAFDB11B3001400" ID="RINCON_5CAAFDB11B3001400:3311607547"><ZoneGroupMember UUID="RINCON_5CAAFDB11B3001400" Location="http://10.10.10.23:1400/xml/device_description.xml" ZoneName="Aleksi" Icon="x-rincon-roomicon:playroom" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="107" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="1" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2422" BehindWifiExtender="0" WifiEnabled="1" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" VirtualLineInSource="spotify" IdleState="1" MoreInfo=""/></ZoneGroup><ZoneGroup Coordinator="RINCON_000E58A1C6C801400" ID="RINCON_000E58A1C6C801400:12"><ZoneGroupMember UUID="RINCON_000E58A1C6C801400" Location="http://10.10.10.20:1400/xml/device_description.xml" ZoneName="Olohuone" Icon="x-rincon-roomicon:living" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="148" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="0" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2437" BehindWifiExtender="0" WifiEnabled="0" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" IdleState="0" MoreInfo=""/></ZoneGroup></ZoneGroups><VanishedDevices></VanishedDevices></ZoneGroupState>
03/30 22:47:22 Warn: [endpoint/sonos/uuid:RINCON_000E58F0774001400] unlink check, dev id: uuid:RINCON_000E58F0774001400, devname: uuid:RINCON_000E58F0774001400, zone coordinator: RINCON_000E58F0774001400, coordinatorid: RINCON_000E58F0774001400
03/30 22:47:22 Trace: [client/sonos] av_status
03/30 22:47:22 Trace: [Olohuone] [zoneplayer/sonos] state from device: PLAYING
03/30 22:47:22 Trace: [Olohuone] [zoneplayer/sonos]  zoneplayer state: Disconnected
03/30 22:47:22 Trace: [endpoint/sonos/uuid:RINCON_5CAAFDB11B3001400] zone update: <ZoneGroupState><ZoneGroups><ZoneGroup Coordinator="RINCON_5CAAFDB11B3001400" ID="RINCON_5CAAFDB11B3001400:3311607547"><ZoneGroupMember UUID="RINCON_5CAAFDB11B3001400" Location="http://10.10.10.23:1400/xml/device_description.xml" ZoneName="Aleksi" Icon="x-rincon-roomicon:playroom" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="107" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="1" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2422" BehindWifiExtender="0" WifiEnabled="1" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" VirtualLineInSource="spotify" IdleState="1" MoreInfo=""/></ZoneGroup><ZoneGroup Coordinator="RINCON_000E58F0774001400" ID="RINCON_000E58F0774001400:11"><ZoneGroupMember UUID="RINCON_000E58F0774001400" Location="http://10.10.10.21:1400/xml/device_description.xml" ZoneName="Työhuone" Icon="x-rincon-roomicon:library" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="226" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="1" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2422" BehindWifiExtender="0" WifiEnabled="1" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" IdleState="1" MoreInfo=""/></ZoneGroup><ZoneGroup Coordinator="RINCON_000E58A1C6C801400" ID="RINCON_000E58A1C6C801400:12"><ZoneGroupMember UUID="RINCON_000E58A1C6C801400" Location="http://10.10.10.20:1400/xml/device_description.xml" ZoneName="Olohuone" Icon="x-rincon-roomicon:living" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="148" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="0" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2437" BehindWifiExtender="0" WifiEnabled="0" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" IdleState="0" MoreInfo=""/></ZoneGroup></ZoneGroups><VanishedDevices></VanishedDevices></ZoneGroupState>
03/30 22:47:22 Warn: [endpoint/sonos/uuid:RINCON_5CAAFDB11B3001400] unlink check, dev id: uuid:RINCON_5CAAFDB11B3001400, devname: uuid:RINCON_5CAAFDB11B3001400, zone coordinator: RINCON_5CAAFDB11B3001400, coordinatorid: RINCON_5CAAFDB11B3001400
03/30 22:47:23 Info: [stats] 7516mb Virtual, 469mb Physical, 98mb Managed, 1787 Handles, 81 Threads
03/30 22:47:23 Trace: [endpoint/sonos/uuid:RINCON_000E58A1C6C801400] zone update: <ZoneGroupState><ZoneGroups><ZoneGroup Coordinator="RINCON_000E58A1C6C801400" ID="RINCON_000E58A1C6C801400:12"><ZoneGroupMember UUID="RINCON_000E58A1C6C801400" Location="http://10.10.10.20:1400/xml/device_description.xml" ZoneName="Olohuone" Icon="x-rincon-roomicon:living" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="148" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="0" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2437" BehindWifiExtender="0" WifiEnabled="0" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" IdleState="0" MoreInfo=""/></ZoneGroup><ZoneGroup Coordinator="RINCON_000E58F0774001400" ID="RINCON_000E58F0774001400:11"><ZoneGroupMember UUID="RINCON_000E58F0774001400" Location="http://10.10.10.21:1400/xml/device_description.xml" ZoneName="Työhuone" Icon="x-rincon-roomicon:library" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="226" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="1" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2422" BehindWifiExtender="0" WifiEnabled="1" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" IdleState="1" MoreInfo=""/></ZoneGroup><ZoneGroup Coordinator="RINCON_5CAAFDB11B3001400" ID="RINCON_5CAAFDB11B3001400:3311607547"><ZoneGroupMember UUID="RINCON_5CAAFDB11B3001400" Location="http://10.10.10.23:1400/xml/device_description.xml" ZoneName="Aleksi" Icon="x-rincon-roomicon:playroom" Configuration="1" SoftwareVersion="55.1-74250" SWGen="1" MinCompatibleVersion="54.0-00000" LegacyCompatibleVersion="36.0-00000" BootSeq="107" TVConfigurationError="0" HdmiCecAvailable="0" WirelessMode="1" WirelessLeafOnly="0" HasConfiguredSSID="1" ChannelFreq="2422" BehindWifiExtender="0" WifiEnabled="1" Orientation="0" RoomCalibrationState="4" SecureRegState="3" VoiceConfigState="0" MicEnabled="0" AirPlayEnabled="0" VirtualLineInSource="spotify" IdleState="1" MoreInfo=""/></ZoneGroup></ZoneGroups></ZoneGroupState>
03/30 22:47:23 Warn: [endpoint/sonos/uuid:RINCON_000E58A1C6C801400] unlink check, dev id: uuid:RINCON_000E58A1C6C801400, devname: uuid:RINCON_000E58A1C6C801400, zone coordinator: RINCON_000E58A1C6C801400, coordinatorid: RINCON_000E58A1C6C801400

It seems if I group 2 Sonos together than it plays OK , but after ungrouping same issue returns

Hi @Pasi_V,

Are you using the newest Sonos firmware? Were there any changes to your network setup or how the Sonos devices were connected when this issue started occurring?

I know you mentioned you rebooted some of the devices, but can you confirm you rebooted the Network Gear, the Core and the Sonos devices themselves as well?

Yes I reeboted them all and changed core back to Centos. All Sonos are updated to lates version.
I did some testing with grouping again and seems like it has now effect on this. Device loose connections grouped or not.

But then I disabled firewalld in Centos and then it started to work. After few songs played I enabled firewalld again and then dropping started again.

So it seems that there are some more ports that must to be open than these:
9003/udp
9100-9200/tcp

1 Like

Hi @Pasi_V,

It’s great news that you were able to narrow down the issue to the CentOS firewall. Please look over the following post for a more in-depth explanation on how to set up your CentOS firewall as this may help:

I saw also lots of other ports coming from Sonos to core, not just IGMP and multicast.
As workaround I added all my Sonos to trusted zone and no issues anymore.

I have Plex running in same server and never had issue with Sonos in there. Probably these software are using different method playing to remote speakers.

1 Like

This topic was automatically closed 36 hours after the last reply. New replies are no longer allowed.