Issue with Live Radio

Greg_Miller2 · October 19, 2020, 1:50pm

Core Machine (Operating system/System info/Roon build number)
Ubuntu 20.04.1 LTS/Core i7 16Gb//1.7 Build 610

Network Details (Including networking gear model/manufacturer and if on WiFi/Ethernet)
Core Ethernet, End Points Mix Wireless and Ehternet

Audio Devices (Specify what device you’re using and its connection type - USB/HDMI/etc.)
Raspberry Pi/Hifiberry DAC and Raspberry Pi audio out

Description Of Issue
No Live Radio station will play - get the “Playback interrupted because a track failed to load” message… this happens for ALL Live Radio stations and ALL endpoints!

See this in the logs when I attempted to play a couple of stations:

10/19 06:36:14 Debug: [easyhttp] [34] GET to https://internetradio.roonlabs.net/2/api/stations/7b5e75fe-96d2-4392-af64-23d39b0c0d74?format=msgpack returned after 175 ms, status code: 304
10/19 06:36:14 Info: [stats] 3763mb Virtual, 602mb Physical, 121mb Managed, 0 Handles, 72 Threads
10/19 06:36:16 Debug: [easyhttp] [35] GET to https://internetradio.roonlabs.net/2/api/stations/7b5e75fe-96d2-4392-af64-23d39b0c0d74?format=msgpack returned after 52 ms, status code: 304
10/19 06:36:16 Trace: [zone voluRest] Playing Channel
10/19 06:36:16 Trace: [zone voluRest] Selecting Source state=Paused
10/19 06:36:16 Info: [library] saved recent ProfileId=01ea6dc8-519b-4ce3-ab63-b7dbb9db13ce Time=10/19/2020 10:36:16 AM DataType=channel Type=play MetadataId=802 ContentId= LibraryId=802
10/19 06:36:16 Info: [zone voluRest] Canceling Pending Sleep
10/19 06:36:16 Trace: [voluRest] [Inactive] [LOADING @ 0:00] WPTF
10/19 06:36:16 Trace: [musicpowerstate] music is playing, preventing idle sleep
10/19 06:36:16 Trace: [internetradio] Channel Player: Fetching URL [https://16813.live.streamtheworld.com/WPTFAMAAC.aac]
10/19 06:36:17 Trace: Attempting to play channel URL: icy://16813.live.streamtheworld.com/WPTFAMAAC.aac
10/19 06:36:17 Info: [voluRest] [zoneplayer] Playing: channel://icy%3a%2f%2f16813.live.streamtheworld.com%2fWPTFAMAAC.aac
10/19 06:36:17 Warn: [zoneplayer] I/O Failure While Opening Sound: icy://16813.live.streamtheworld.com/WPTFAMAAC.aac: Read failure: ExternalProcMissing
10/19 06:36:17 Info: [voluRest] [zoneplayer]     Open Result (Playing):Result[Status=IOError]
10/19 06:36:17 Warn: [zoneplayer] couldn't play URL channel://icy%3a%2f%2f16813.live.streamtheworld.com%2fWPTFAMAAC.aac: other error
10/19 06:36:17 Warn: [zone voluRest] Track Stopped Due to Error
10/19 06:36:17 Trace: Attempting to play channel URL: icy://16813.live.streamtheworld.com/WPTFAMAAC.aac
10/19 06:36:17 Info: [voluRest] [zoneplayer] Playing: channel://icy%3a%2f%2f16813.live.streamtheworld.com%2fWPTFAMAAC.aac
10/19 06:36:17 Info: [zone voluRest] OnPlayFeedback StoppedEndOfMediaUnnatural
10/19 06:36:17 Info: [zone voluRest] OnPlayFeedback Stopped
10/19 06:36:17 Info: [zone voluRest] Canceling Pending Sleep
10/19 06:36:17 Info: [zone voluRest] Canceling Pending Sleep
10/19 06:36:17 Trace: [voluRest] [Inactive] [STOPPED @ 0:00] WPTF
10/19 06:36:17 Debug: [easyhttp] [36] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 49 ms, status code: 304
10/19 06:36:17 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:17 Trace: [radio/library] got location 
10/19 06:36:17 Debug: [easyhttp] [38] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 51 ms, status code: 400
10/19 06:36:17 Debug: [easyhttp] [39] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 47 ms, status code: 304
10/19 06:36:17 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:17 Trace: [radio/library] got location 
10/19 06:36:17 Debug: [easyhttp] [37] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 163 ms, status code: 304
10/19 06:36:17 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:17 Trace: [radio/library] got location 
10/19 06:36:17 Debug: [easyhttp] [40] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 48 ms, status code: 400
10/19 06:36:17 Debug: [easyhttp] [41] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 48 ms, status code: 400
10/19 06:36:17 Debug: [easyhttp] [42] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 48 ms, status code: 304
10/19 06:36:17 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:17 Trace: [radio/library] got location 
10/19 06:36:17 Debug: [easyhttp] [43] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 51 ms, status code: 400
10/19 06:36:17 Warn: [zoneplayer] I/O Failure While Opening Sound: icy://16813.live.streamtheworld.com/WPTFAMAAC.aac: Read failure: ExternalProcMissing
10/19 06:36:17 Info: [voluRest] [zoneplayer]     Open Result (Playing):Result[Status=IOError]
10/19 06:36:27 Trace: [radio/query] performing channel query
10/19 06:36:27 Trace: [radio/query] query returned 7 Sooloos.Broker.Api.Channel(s)
10/19 06:36:27 Trace: [radio/query] performing channel query
10/19 06:36:27 Trace: [radio/query] query returned 7 Sooloos.Broker.Api.Channel(s)
10/19 06:36:27 Debug: [easyhttp] [44] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=402&format=msgpack returned after 191 ms, status code: 200
10/19 06:36:27 Debug: [easyhttp] [50] GET to https://internetradio.roonlabs.net/2/api/languages/all?language=&count=20000&format=msgpack returned after 189 ms, status code: 304
10/19 06:36:27 Debug: [easyhttp] [46] GET to https://internetradio.roonlabs.net/2/api/languages/all?language=&count=20000&format=msgpack returned after 207 ms, status code: 304
10/19 06:36:27 Debug: [easyhttp] [45] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&format=msgpack returned after 212 ms, status code: 200
10/19 06:36:27 Debug: [easyhttp] [51] GET to https://internetradio.roonlabs.net/2/api/categories/toplevel?count=20000&format=msgpack returned after 198 ms, status code: 304
10/19 06:36:27 Debug: [easyhttp] [47] GET to https://internetradio.roonlabs.net/2/api/categories/toplevel?count=20000&format=msgpack returned after 209 ms, status code: 304
10/19 06:36:27 Debug: [easyhttp] [49] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&format=msgpack returned after 311 ms, status code: 200
10/19 06:36:27 Debug: [easyhttp] [48] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=402&format=msgpack returned after 315 ms, status code: 200
10/19 06:36:29 Info: [stats] 3847mb Virtual, 621mb Physical, 137mb Managed, 0 Handles, 74 Threads
10/19 06:36:31 Debug: [easyhttp] [52] GET to https://internetradio.roonlabs.net/2/api/stations/fc5c46ed-f6f8-485e-b124-a23db1b83a91?format=msgpack returned after 41 ms, status code: 200
10/19 06:36:32 Debug: [easyhttp] [53] GET to https://internetradio.roonlabs.net/2/api/stations/fc5c46ed-f6f8-485e-b124-a23db1b83a91?format=msgpack returned after 43 ms, status code: 304
10/19 06:36:32 Trace: [zone voluRest] Playing Channel
10/19 06:36:32 Info: [library] saved recent ProfileId=01ea6dc8-519b-4ce3-ab63-b7dbb9db13ce Time=10/19/2020 10:36:32 AM DataType=channel Type=play MetadataId=1058 ContentId= LibraryId=
10/19 06:36:32 Trace: [zone voluRest] Selecting Source state=Stopped
10/19 06:36:32 Trace: [internetradio] Channel Player: Fetching URL [http://94.23.201.38:8010/;mp3]
10/19 06:36:32 Trace: [voluRest] [Inactive] [LOADING @ 0:00] Audiophile Classical
10/19 06:36:32 Trace: [musicpowerstate] music is playing, preventing idle sleep
10/19 06:36:33 Trace: Attempting to play channel URL: icy://94.23.201.38:8010/;mp3
10/19 06:36:33 Info: [voluRest] [zoneplayer] Playing: channel://icy%3a%2f%2f94.23.201.38%3a8010%2f%3bmp3
10/19 06:36:33 Warn: [zoneplayer] I/O Failure While Opening Sound: icy://94.23.201.38:8010/;mp3: Read failure: ExternalProcMissing
10/19 06:36:33 Info: [voluRest] [zoneplayer]     Open Result (Playing):Result[Status=IOError]
10/19 06:36:33 Warn: [zoneplayer] couldn't play URL channel://icy%3a%2f%2f94.23.201.38%3a8010%2f%3bmp3: other error
10/19 06:36:33 Warn: [zone voluRest] Track Stopped Due to Error
10/19 06:36:33 Trace: Attempting to play channel URL: icy://94.23.201.38:8010/;mp3
10/19 06:36:33 Info: [voluRest] [zoneplayer] Playing: channel://icy%3a%2f%2f94.23.201.38%3a8010%2f%3bmp3
10/19 06:36:33 Info: [zone voluRest] OnPlayFeedback StoppedEndOfMediaUnnatural
10/19 06:36:33 Info: [zone voluRest] OnPlayFeedback Stopped
10/19 06:36:33 Info: [zone voluRest] Canceling Pending Sleep
10/19 06:36:33 Info: [zone voluRest] Canceling Pending Sleep
10/19 06:36:33 Trace: [voluRest] [Inactive] [STOPPED @ 0:00] Audiophile Classical
10/19 06:36:33 Debug: [easyhttp] [55] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 51 ms, status code: 304
10/19 06:36:33 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:33 Trace: [radio/library] got location 
10/19 06:36:33 Debug: [easyhttp] [54] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 78 ms, status code: 304
10/19 06:36:33 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:33 Trace: [radio/library] got location 
10/19 06:36:33 Debug: [easyhttp] [56] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 69 ms, status code: 400
10/19 06:36:33 Debug: [easyhttp] [57] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack returned after 50 ms, status code: 400
10/19 06:36:33 Debug: [easyhttp] [58] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 53 ms, status code: 304
10/19 06:36:33 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:33 Trace: [radio/library] got location 
10/19 06:36:33 Debug: [easyhttp] [59] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack returned after 77 ms, status code: 304
10/19 06:36:33 Error: could not write key: internetradio_location =  -- Object reference not set to an instance of an object
10/19 06:36:33 Trace: [radio/library] got location 
10/19 06:36:33 Warn: [zoneplayer] I/O Failure While Opening Sound: icy://94.23.201.38:8010/;mp3: Read failure: ExternalProcMissing
10/19 06:36:33 Info: [voluRest] [zoneplayer]     Open Result (Playing):Result[Status=IOError]
10/19 06:36:33 Debug: [easyhttp] [61] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 52 ms, status code: 400
10/19 06:36:33 Debug: [easyhttp] [60] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack returned after 73 ms, status code: 400

BrianW · October 19, 2020, 3:31pm

What happens if you try a flac stream? For instance
TMEFolk Radio

Greg_Miller2 · October 19, 2020, 5:02pm

Interesting! That station played just fine.

Greg_Miller2 · October 19, 2020, 5:16pm

I did just update to Ubuntu 20.04, wonder if Roon is missing a dependency?
Here is the output from sudo systemctl status roonserver.service
oonserver.service - RoonServer
Loaded: loaded (/etc/systemd/system/roonserver.service; enabled; vendor preset: enabled)
Active: active (running) since Wed 2020-10-14 09:46:57 EDT; 5 days ago
Main PID: 193949 (start.sh)
Tasks: 116 (limit: 14297)
Memory: 1.8G
CGroup: /system.slice/roonserver.service
├─193949 /bin/bash /opt/RoonServer/start.sh
├─193958 /opt/RoonMono/bin/RoonServer --debug --gc=sgen --server RoonServer.exe
├─579027 /opt/RoonMono/bin/RoonAppliance --debug --gc=sgen --server RoonAppliance.exe -watchdogport=38693
├─579028 /opt/Server/processreaper 579027
└─579174 /opt/RoonMono/bin/RAATServer --debug --gc=sgen --server RAATServer.exe

Oct 19 05:02:20 redmenaceubuntu start.sh[193958]: Running
Oct 19 12:03:29 redmenaceubuntu start.sh[579027]: atom with <8 bytes is invalidSystem.Net.Sockets.SocketException (0x80004005): Connection reset by peer
Oct 19 12:03:29 redmenaceubuntu start.sh[579027]: at System.Net.Sockets.Socket.EndReceive (System.IAsyncResult asyncResult) [0x00012] in <69f8661c94644655a06862d3e6b439f6>:0
Oct 19 12:03:29 redmenaceubuntu start.sh[579027]: at Sooloos.RnetJsonClient+<>c__DisplayClass65_0.<_BeginRead>b__0 (System.IAsyncResult ar) [0x00046] in <51c894eb252e4920bfd1a29ff0dd38fc>:0
Oct 19 12:56:40 redmenaceubuntu start.sh[579027]: System.Net.Sockets.SocketException (0x80004005): Connection reset by peer
Oct 19 12:56:40 redmenaceubuntu start.sh[579027]: at System.Net.Sockets.Socket.EndReceive (System.IAsyncResult asyncResult) [0x00012] in <69f8661c94644655a06862d3e6b439f6>:0
Oct 19 12:56:40 redmenaceubuntu start.sh[579027]: at Sooloos.RnetJsonClient+<>c__DisplayClass65_0.<_BeginRead>b__0 (System.IAsyncResult ar) [0x00046] in <51c894eb252e4920bfd1a29ff0dd38fc>:0
Oct 19 12:59:14 redmenaceubuntu start.sh[579027]: System.Net.Sockets.SocketException (0x80004005): Connection reset by peer
Oct 19 12:59:14 redmenaceubuntu start.sh[579027]: at System.Net.Sockets.Socket.EndReceive (System.IAsyncResult asyncResult) [0x00012] in <69f8661c94644655a06862d3e6b439f6>:0
Oct 19 12:59:14 redmenaceubuntu start.sh[579027]: at Sooloos.RnetJsonClient+<>c__DisplayClass65_0.<_BeginRead>b__0 (System.IAsyncResult ar) [0x00046] in <51c894eb252e4920bfd1a29ff0

Greg_Miller2 · October 19, 2020, 5:22pm

Just ran this command
sudo apt-get install ffmpeg
…and that fixed the issue…I guess the inplace upgrade from Ubuntu 18 to 20 did not upgrade that package.

Anyway, I’m back up and running with Live Radio!
Thank you!

BrianW · October 19, 2020, 5:26pm

Excellent.

system · October 21, 2020, 5:26am

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