RoPieee with Roon something stops Roon playback

I have been using RoPieee for about 2 years now with great success - thanks @spockfish

My use case might be. an edge case.
I run Roon ROCK on an 11th Gen i7 NUC with 32GB of emmory and it has been solid in this configuration for around 6 months now. RoPieee is running on a Raspberry Pi 4b 2Gb and I keep it updated to the latest stable version. AirPlay is normally enabled.
My RoPieee has an IQaudIO Pi-DigiAmp+ and speakers ina small footprint case similar to an internet radio excpet it’s primary source is Roon. It is sited in the Kitchen and is used most of the day with Live Radio running. I have always had one issue which i could not get to the bottom of. From time to time play would justs stop. It is very intermittant - sometimes once a day somtimes 2 or three times in quick succession (over several minutes say). This has happened with other Roon sources but is most noticieable with Live Radio as you expect it to be continuous. For a long while I put it down to stream stability. However I noticed that play stopping often conincided with me entering the room. I then noticed similar behaviour if my wife entered the room. After the longest while I realised that the common factor seemed to be an Apple device in the room (i.e. iphone iPad etc.). If my understanding is correct AirPlay devices broadcast their availability via mDNS/Bonjour, and Apple devices continuously scan for AirPlay-compatible targets. If so is the RoPieee end point seeing the scan and expecting a ‘play to AirPlay’ command and sends a ‘pause’ command to Roon ? Roon sees the pause command but becuase it is live Radio and pause is not appropriate it converts the command to stop. This pause/stop sequence is what I see in Roon’s logs when this happens.

I have temporarily disabled AirPlay and in the last 48 hours no pause/stop events which seems to confirm it is AirPlay.

I am not expecting you to change the behaviour (unless it is a trivial change) but it would be great to have it confirmed that this hypothesis is correct. I.E. having AirPlay enabled can lead to unexpected pause/stop events?
I have sent a log 412e62f45da49e33 but not sure that will be informative after the period involved.

Many thanks as ever for your hard work

here is an excerpt from the Roon log during a pause/stop event

04/09 06:42:53 \[Local 04/09 07:42:53\] Debug: \[easyhttp\] \[94584\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 194 ms, status code: 200, request body size: 0 B
04/09 06:42:54 \[Local 04/09 07:42:54\] Info: \[stats\] 10405mb Virtual, 1990mb Physical, 1158mb Managed, 832mb estimated Unmanaged, 424 Handles, 77 Threads, 0.65% of runtime in GC pauses, 18ms last GC pause duration
04/09 06:42:55 \[Local 04/09 07:42:55\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[100% buf\] \[PLAYING @ 4:37\] Steppenwolf - Born To Be Wild
04/09 06:42:58 \[Local 04/09 07:42:58\] Debug: \[easyhttp\] \[94585\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 180 ms, status code: 200, request body size: 0 B
04/09 06:42:59 \[Local 04/09 07:42:59\] Debug: \[easyhttp\] \[94586\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio%3D320000.norewind.m3u8 returned after 91 ms, status code: 200, request body size: 0 B
04/09 06:42:59 \[Local 04/09 07:42:59\] Debug: \[easyhttp\] \[94587\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio=320000-277455781.ts returned after 100 ms, status code: 200, request body size: 0 B
04/09 06:43:00 \[Local 04/09 07:43:00\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[100% buf\] \[PLAYING @ 4:42\] Steppenwolf - Born To Be Wild
04/09 06:43:02 \[Local 04/09 07:43:02\] Trace: \[roonapi\] \[apiclient 192.168.1.149:35644\] CONTINUE Changed {“message”:“Extension Manager already up to date”,“is_error”:false}
04/09 06:43:02 \[Local 04/09 07:43:02\] Trace: \[library\] endmutation in 26ms
04/09 06:43:03 \[Local 04/09 07:43:03\] Debug: \[easyhttp\] \[94588\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 203 ms, status code: 200, request body size: 0 B
04/09 06:43:05 \[Local 04/09 07:43:05\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[100% buf\] \[PLAYING @ 4:47\] Steppenwolf - Born To Be Wild
04/09 06:43:06 \[Local 04/09 07:43:06\] Debug: \[easyhttp\] \[94589\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio%3D320000.norewind.m3u8 returned after 84 ms, status code: 200, request body size: 0 B
04/09 06:43:06 \[Local 04/09 07:43:06\] Debug: \[easyhttp\] \[94590\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio=320000-277455782.ts returned after 77 ms, status code: 200, request body size: 0 B
04/09 06:43:08 \[Local 04/09 07:43:08\] Debug: \[easyhttp\] \[94591\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 184 ms, status code: 200, request body size: 0 B
04/09 06:43:09 \[Local 04/09 07:43:09\] Trace: \[zone KitchenPi\] Pause
04/09 06:43:09 \[Local 04/09 07:43:09\] Info: \[zone KitchenPi\] OnPlayFeedback Stopped
04/09 06:43:09 \[Local 04/09 07:43:09\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/09 06:43:09 \[Local 04/09 07:43:09\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/09 06:43:09 \[Local 04/09 07:43:09\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[100% buf\] \[STOPPED @ 0:00\] Steppenwolf - Born To Be Wild
04/09 06:43:09 \[Local 04/09 07:43:09\] Info: \[audio/env\] \[zoneplayer → stream\] All streams were disposed
04/09 06:43:09 \[Local 04/09 07:43:09\] Trace: \[KitchenPi\] \[zoneplayer/raat\] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/09 06:43:09 \[Local 04/09 07:43:09\] Trace: \[IQaudIODAC\] \[raatclient\] SENT \[12\]{“request”:“end_stream”}
04/09 06:43:09 \[Local 04/09 07:43:09\] Debug: \[raat/tcpaudiosource\] disconnecting

It should not.
Whether something is happening… that I don’t know. But functionality wise this is not implemented.

Would be great if you can ‘catch’ this happening, and send me feedback with some additional information (timestamp).

Thanks for the prompt response. I will re-enable AirPlay and attempt to catch it - shoule be able to catch the time stamp from the Roon log but it may take a day or two to witness a trigger.

1 Like

OK finally caught an unexpected pause.
This is from the Roon log:

04/20 16:25:12 \[Local 04/20 17:25:12\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[PLAYING @ 108:46\] Rozalla - Everybody’s Free (To Feel Good)
04/20 16:25:14 \[Local 04/20 17:25:14\] Debug: \[easyhttp\] \[244858\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio%3D320000.norewind.m3u8 returned after 75 ms, status code: 200, request body size: 0 B
04/20 16:25:14 \[Local 04/20 17:25:14\] Debug: \[easyhttp\] \[244859\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio=320000-277609739.ts returned after 83 ms, status code: 200, request body size: 0 B
04/20 16:25:17 \[Local 04/20 17:25:17\] Debug: \[easyhttp\] \[244860\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 184 ms, status code: 200, request body size: 0 B
04/20 16:25:17 \[Local 04/20 17:25:17\] Trace: \[library\] endmutation in 14ms
04/20 16:25:17 \[Local 04/20 17:25:17\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[PLAYING @ 108:51\] Rozalla - Everybody’s Free (To Feel Good)
04/20 16:25:18 \[Local 04/20 17:25:18\] Trace: \[zone KitchenPi\] Pause
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: \[zone KitchenPi\] OnPlayFeedback Stopped
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/20 16:25:18 \[Local 04/20 17:25:18\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[STOPPED @ 0:00\] Rozalla - Everybody’s Free (To Feel Good)
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: \[audio/env\] \[zoneplayer → stream\] All streams were disposed
04/20 16:25:18 \[Local 04/20 17:25:18\] Trace: \[KitchenPi\] \[zoneplayer/raat\] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/20 16:25:18 \[Local 04/20 17:25:18\] Trace: \[IQaudIODAC\] \[raatclient\] SENT \[108\]{“request”:“end_stream”}
04/20 16:25:18 \[Local 04/20 17:25:18\] Debug: \[raat/tcpaudiosource\] disconnecting
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: \[audio/env\] \[zoneplayer → stream → endpoint\] All streams were disposed
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: \[audio/env\] \[zoneplayer\] All streams were disposed
04/20 16:25:18 \[Local 04/20 17:25:18\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[99\] {“status”:“Ended”}
04/20 16:25:18 \[Local 04/20 17:25:18\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[108\] {“status”:“Success”}
04/20 16:25:18 \[Local 04/20 17:25:18\] Info: sleep 39ms after flush

I have sent feedback ref: b99c1ec4425d715f
Hope this helps?

Hi @spockfish This morning I have observed 3 more examples and have sent a further feedback file ref 53864afbcc8f873b

For info .122 is the rock server and .29 and .240 are laptop connections

1st occurrence

04/21 07:30:40 \[Local 04/21 08:30:40\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[PLAYING @ 50:28\] Dua Lipa - New Rules
04/21 07:30:41 \[Local 04/21 08:30:41\] Trace: \[KitchenPi\] \[zoneplayer/raat\] sync IQaudIODAC: realtime=59856355634982 rtt=2000us offset=43735711634us delta=41us drift=36123us in 3029.0435s (11.926ppm, 42.933ms/hr)
04/21 07:30:42 \[Local 04/21 08:30:42\] Debug: \[easyhttp\] \[248785\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 192 ms, status code: 200, request body size: 0 B
04/21 07:30:42 \[Local 04/21 08:30:42\] Info: \[stats\] 12142mb Virtual, 2491mb Physical, 1757mb Managed, 734mb estimated Unmanaged, 407 Handles, 92 Threads, 0.95% of runtime in GC pauses, 21ms last GC pause duration
04/21 07:30:43 \[Local 04/21 08:30:43\] Warn: \[remoting/remotingprotocolv2\] \[remotingprotocol\] read 0 bytes, error code: ConnectionReset
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[remoting/serverconnectionv2\] Client disconnected: 192.168.1.240:62441
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[remoting/brokerserver\] \[initconn 192.168.1.29:56346=>192.168.1.122:9332\] Connected
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[remoting/brokerserver\] \[initconn 192.168.1.29:56346=>192.168.1.122:9332\] Resumed Session
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[remoting/remotingprotocolv2\] resume send 482 messages, 19KiB
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[raat\] \[sood\] Refreshing device list
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[raatserver\] \[sood\] Refreshing device list
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[zone KitchenPi\] Pause
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[zone KitchenPi\] OnPlayFeedback Stopped
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[STOPPED @ 0:00\] Dua Lipa - New Rules
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[KitchenPi\] \[zoneplayer/raat\] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[IQaudIODAC\] \[raatclient\] SENT \[9\]{“request”:“end_stream”}
04/21 07:30:43 \[Local 04/21 08:30:43\] Debug: \[raat/tcpaudiosource\] disconnecting
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[audio/env\] \[zoneplayer → stream\] All streams were disposed
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[audio/env\] \[zoneplayer → stream → endpoint\] All streams were disposed
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: \[audio/env\] \[zoneplayer\] All streams were disposed
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[7\] {“status”:“Ended”}
04/21 07:30:43 \[Local 04/21 08:30:43\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[9\] {“status”:“Success”}
04/21 07:30:43 \[Local 04/21 08:30:43\] Info: sleep 39ms after flush

2nd occurrence

04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[remoting/serverconnectionv2\] Client disconnected: 192.168.1.240:62441
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[remoting/brokerserver\] \[initconn 192.168.1.29:56522=>192.168.1.122:9332\] Connected
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[remoting/brokerserver\] \[initconn 192.168.1.29:56522=>192.168.1.122:9332\] Resumed Session
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[remoting/remotingprotocolv2\] resume send 1774 messages, 71KiB
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[raat\] \[sood\] Refreshing device list
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[raatserver\] \[sood\] Refreshing device list
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[zone KitchenPi\] Pause
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[zone KitchenPi\] OnPlayFeedback Stopped
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[STOPPED @ 0:00\] Bryan Ferry - Let’s Stick Together
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[audio/env\] \[zoneplayer → stream\] All streams were disposed
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[KitchenPi\] \[zoneplayer/raat\] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[IQaudIODAC\] \[raatclient\] SENT \[15\]{“request”:“end_stream”}
04/21 08:31:05 \[Local 04/21 09:31:05\] Debug: \[raat/tcpaudiosource\] disconnecting
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[audio/env\] \[zoneplayer → stream → endpoint\] All streams were disposed
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: \[audio/env\] \[zoneplayer\] All streams were disposed
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[10\] {“status”:“Ended”}
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[library\] endmutation in 19ms
04/21 08:31:05 \[Local 04/21 09:31:05\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[15\] {“status”:“Success”}
04/21 08:31:05 \[Local 04/21 09:31:05\] Info: sleep 39ms after flush

3rd occurrence

04/21 08:33:05 \[Local 04/21 09:33:05\] Warn: \[remoting/remotingprotocolv2\] \[remotingprotocol\] read 0 bytes, error code: ConnectionReset
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[remoting/serverconnectionv2\] Client disconnected: 192.168.1.240:62441
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[remoting/brokerserver\] \[initconn 192.168.1.29:56581=>192.168.1.122:9332\] Connected
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[remoting/brokerserver\] \[initconn 192.168.1.29:56581=>192.168.1.122:9332\] Resumed Session
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[remoting/remotingprotocolv2\] resume send 113 messages, 4KiB
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[raat\] \[sood\] Refreshing device list
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[raatserver\] \[sood\] Refreshing device list
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[zone KitchenPi\] Pause
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[zone KitchenPi\] OnPlayFeedback Stopped
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[zone KitchenPi\] Canceling Pending Sleep
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[STOPPED @ 0:00\] Belinda Carlisle - We Want The Same Thing
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[audio/env\] \[zoneplayer → stream\] All streams were disposed
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[audio/env\] \[zoneplayer → stream → endpoint\] All streams were disposed
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[KitchenPi\] \[zoneplayer/raat\] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[IQaudIODAC\] \[raatclient\] SENT \[18\]{“request”:“end_stream”}
04/21 08:33:05 \[Local 04/21 09:33:05\] Debug: \[raat/tcpaudiosource\] disconnecting
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: \[audio/env\] \[zoneplayer\] All streams were disposed
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[16\] {“status”:“Ended”}
04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[18\] {“status”:“Success”}
04/21 08:33:05 \[Local 04/21 09:33:05\] Info: sleep 39ms after flush

Hope this helps?

I have now disabled AirPlay but still had an issue after around 3 hours.

04/21 12:54:18 \[Local 04/21 13:54:18\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[PLAYING @ 185:56\] Jeremy Vine - Mandelson and job juggling 04/21 12:54:22 \[Local 04/21 13:54:22\] Debug: \[easyhttp\] \[258679\] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 186 ms, status code: 200, request body size: 0 B 04/21 12:54:23 \[Local 04/21 13:54:23\] Debug: \[easyhttp\] \[258680\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio%3d320000.norewind.m3u8 returned after 67 ms, status code: 200, request body size: 0 B 04/21 12:54:23 \[Local 04/21 13:54:23\] Debug: \[easyhttp\] \[258681\] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio=320000-277621262.ts returned after 80 ms, status code: 200, request body size: 0 B 04/21 12:54:23 \[Local 04/21 13:54:23\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[PLAYING @ 186:01\] Jeremy Vine - Mandelson and job juggling 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[zone KitchenPi\] Pause 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: \[zone KitchenPi\] OnPlayFeedback Stopped 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: \[zone KitchenPi\] Canceling Pending Sleep 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: \[zone KitchenPi\] Canceling Pending Sleep 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[KitchenPi\] \[LowQuality, 24/48 AAC => 24/48\] \[STOPPED @ 0:00\] Jeremy Vine - Mandelson and job juggling 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: \[audio/env\] \[zoneplayer -> stream\] All streams were disposed 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[KitchenPi\] \[zoneplayer/raat\] Endpoint IQaudIODAC State Changed: Playing => Prepared 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[IQaudIODAC\] \[raatclient\] SENT \[10\]{"request":"end_stream"} 04/21 12:54:25 \[Local 04/21 13:54:25\] Debug: \[raat/tcpaudiosource\] disconnecting 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: \[audio/env\] \[zoneplayer -> stream -> endpoint\] All streams were disposed 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[7\] {"status":"Ended"} 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: \[audio/env\] \[zoneplayer\] All streams were disposed 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.100:55296\] CONTINUE Changed {"zones_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","display_name":"KitchenPi","outputs":\[{"output_id":"17012544785f8b36a95b4cf93ed846179af7","zone_id":"16012544785f8b36a95b4cf93ed846179af7","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"KitchenPi","volume":{"type":"number","min":0,"max":100,"value":20,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"IQaudIODAC","supports_standby":false,"status":"indeterminate"}\]}\],"state":"stopped","is_next_allowed":false,"is_previous_allowed":false,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":11,"queue_time_remaining":2819,"settings":{"loop":"loop","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"one_line":{"line1":"Jeremy Vine - Mandelson and job juggling"},"two_line":{"line1":"BBC Radio 2","line2":"Jeremy Vine - Mandelson and job juggling"},"three_line":{"line1":"BBC Radio 2","line2":"Mandelson and job juggling","line3":"Jeremy Vine"},"image_key":"afd611ddf80b5dce68aa13b8e9e99aff8bf23d351d0918d67f032e52c91c6dc24882ed6107bcb78f714d78cf03afae0077035637eef8ce4da32f7204e4ee1e9f8c69e82297a0308d0a300583c2edea9ab042ceba5d79bcc677e62cad1eec99cf54f4a08144af13fbfd67d6831af9a41b"}}\],"zones_seek_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","queue_time_remaining":2819,"seek_position":null}\]} 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[IQaudIODAC\] \[raatclient\] GOT \[10\] {"status":"Success"} 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.149:55626\] CONTINUE Changed {"zones_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","display_name":"KitchenPi","outputs":\[{"output_id":"17012544785f8b36a95b4cf93ed846179af7","zone_id":"16012544785f8b36a95b4cf93ed846179af7","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"KitchenPi","volume":{"type":"number","min":0,"max":100,"value":20,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"IQaudIODAC","supports_standby":false,"status":"indeterminate"}\]}\],"state":"stopped","is_next_allowed":false,"is_previous_allowed":false,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":11,"queue_time_remaining":2819,"settings":{"loop":"loop","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"one_line":{"line1":"Jeremy Vine - Mandelson and job juggling"},"two_line":{"line1":"BBC Radio 2","line2":"Jeremy Vine - Mandelson and job juggling"},"three_line":{"line1":"BBC Radio 2","line2":"Mandelson and job juggling","line3":"Jeremy Vine"},"image_key":"afd611ddf80b5dce68aa13b8e9e99aff8bf23d351d0918d67f032e52c91c6dc24882ed6107bcb78f714d78cf03afae0077035637eef8ce4da32f7204e4ee1e9f8c69e82297a0308d0a300583c2edea9ab042ceba5d79bcc677e62cad1eec99cf54f4a08144af13fbfd67d6831af9a41b"}}\],"zones_seek_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","queue_time_remaining":2819,"seek_position":null}\]} 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.149:55628\] CONTINUE Changed {"zones_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","display_name":"KitchenPi","outputs":\[{"output_id":"17012544785f8b36a95b4cf93ed846179af7","zone_id":"16012544785f8b36a95b4cf93ed846179af7","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"KitchenPi","volume":{"type":"number","min":0,"max":100,"value":20,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"IQaudIODAC","supports_standby":false,"status":"indeterminate"}\]}\],"state":"stopped","is_next_allowed":false,"is_previous_allowed":false,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":11,"queue_time_remaining":2819,"settings":{"loop":"loop","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"one_line":{"line1":"Jeremy Vine - Mandelson and job juggling"},"two_line":{"line1":"BBC Radio 2","line2":"Jeremy Vine - Mandelson and job juggling"},"three_line":{"line1":"BBC Radio 2","line2":"Mandelson and job juggling","line3":"Jeremy Vine"},"image_key":"afd611ddf80b5dce68aa13b8e9e99aff8bf23d351d0918d67f032e52c91c6dc24882ed6107bcb78f714d78cf03afae0077035637eef8ce4da32f7204e4ee1e9f8c69e82297a0308d0a300583c2edea9ab042ceba5d79bcc677e62cad1eec99cf54f4a08144af13fbfd67d6831af9a41b"}}\],"zones_seek_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","queue_time_remaining":2819,"seek_position":null}\]} 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.176:43032\] CONTINUE Changed {"zones_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","display_name":"KitchenPi","outputs":\[{"output_id":"17012544785f8b36a95b4cf93ed846179af7","zone_id":"16012544785f8b36a95b4cf93ed846179af7","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"KitchenPi","volume":{"type":"number","min":0,"max":100,"value":20,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"IQaudIODAC","supports_standby":false,"status":"indeterminate"}\]}\],"state":"stopped","is_next_allowed":false,"is_previous_allowed":false,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":11,"queue_time_remaining":2819,"settings":{"loop":"loop","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"one_line":{"line1":"Jeremy Vine - Mandelson and job juggling"},"two_line":{"line1":"BBC Radio 2","line2":"Jeremy Vine - Mandelson and job juggling"},"three_line":{"line1":"BBC Radio 2","line2":"Mandelson and job juggling","line3":"Jeremy Vine"},"image_key":"afd611ddf80b5dce68aa13b8e9e99aff8bf23d351d0918d67f032e52c91c6dc24882ed6107bcb78f714d78cf03afae0077035637eef8ce4da32f7204e4ee1e9f8c69e82297a0308d0a300583c2edea9ab042ceba5d79bcc677e62cad1eec99cf54f4a08144af13fbfd67d6831af9a41b"}}\],"zones_seek_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","queue_time_remaining":2819,"seek_position":null}\]} 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.103:36850\] CONTINUE Changed {"zones_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","display_name":"KitchenPi","outputs":\[{"output_id":"17012544785f8b36a95b4cf93ed846179af7","zone_id":"16012544785f8b36a95b4cf93ed846179af7","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"KitchenPi","volume":{"type":"number","min":0,"max":100,"value":20,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"IQaudIODAC","supports_standby":false,"status":"indeterminate"}\]}\],"state":"stopped","is_next_allowed":false,"is_previous_allowed":false,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":11,"queue_time_remaining":2819,"settings":{"loop":"loop","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"one_line":{"line1":"Jeremy Vine - Mandelson and job juggling"},"two_line":{"line1":"BBC Radio 2","line2":"Jeremy Vine - Mandelson and job juggling"},"three_line":{"line1":"BBC Radio 2","line2":"Mandelson and job juggling","line3":"Jeremy Vine"},"image_key":"afd611ddf80b5dce68aa13b8e9e99aff8bf23d351d0918d67f032e52c91c6dc24882ed6107bcb78f714d78cf03afae0077035637eef8ce4da32f7204e4ee1e9f8c69e82297a0308d0a300583c2edea9ab042ceba5d79bcc677e62cad1eec99cf54f4a08144af13fbfd67d6831af9a41b"}}\],"zones_seek_changed":\[{"zone_id":"16012544785f8b36a95b4cf93ed846179af7","queue_time_remaining":2819,"seek_position":null}\]} 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.176:43032\] GOT com.roonlabs.transport:2/get_outputs 04/21 12:54:25 \[Local 04/21 13:54:25\] Trace: \[roonapi\] \[apiclient 192.168.1.176:43032\] COMPLETE Success {"outputs":\[{"output_id":"170194a857b417419f32ff0a76d99d6c84bf","zone_id":"160194a857b417419f32ff0a76d99d6c84bf","can_group_with_output_ids":\["170194a857b417419f32ff0a76d99d6c84bf"\],"display_name":"Workshop RoomPlayer","volume":{"type":"number","min":0,"max":100,"value":61,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"SqueezeLite","supports_standby":true,"status":"indeterminate"}\]},{"output_id":"1701ae70929048f38ccc6325041a34078831","zone_id":"1601ae70929048f38ccc6325041a34078831","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"iFi","source_controls":\[{"control_key":"1","display_name":"iFi (by AMR) HD USB Audio","supports_standby":false,"status":"indeterminate"}\]},{"output_id":"17012edd817deaa47a40a5c0bc3400a02228","zone_id":"16012edd817deaa47a40a5c0bc3400a02228","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"Study Aries","volume":{"type":"number","min":0,"max":100,"value":29,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"AURALiC ARIES_MINI","supports_standby":true,"status":"selected"}\]},{"output_id":"17012edd817deaa47a40a5c0bc3400a04edf","zone_id":"16012edd817deaa47a40a5c0bc3400a04edf","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"HiFi Altair","volume":{"type":"number","min":0,"max":100,"value":61,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"AURALiC ALTAIR_G1","supports_standby":true,"status":"selected"}\]},{"output_id":"17012544785f8b36a95b4cf93ed846179af7","zone_id":"16012544785f8b36a95b4cf93ed846179af7","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"KitchenPi","volume":{"type":"number","min":0,"max":100,"value":20,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":\[{"control_key":"1","display_name":"IQaudIODAC","supports_standby":false,"status":"indeterminate"}\]},{"output_id":"170110e912c3ef3f5a937b753378264aae0d","zone_id":"160110e912c3ef3f5a937b753378264aae0d","can_group_with_output_ids":\["17012544785f8b36a95b4cf93ed846179af7","17012edd817deaa47a40a5c0bc3400a02228","17012edd817deaa47a40a5c0bc3400a04edf","1701ae70929048f38ccc6325041a34078831","170110e912c3ef3f5a937b753378264aae0d"\],"display_name":"MacBook Air M1","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":"MacBook Air Speakers","supports_standby":false,"status":"indeterminate"}\]}\]} 04/21 12:54:25 \[Local 04/21 13:54:25\] Info: sleep 39ms after flush

Have sent another feedback file b75d8544f77f0aa8

So it looks as though it isn’t anything to do with AirPlay after. all but is as if RAAT is issuing the command directly without user interactionfor some reason.
Sorry for the bombardment of info and logs and hope you can make sense of it

Well, your logs seem ok… on first sight.

Are you aware that you have a remote control (OSMC) configured? Just want to make sure that something does not trigger a play/pause command (which I see regularly).

Yes I do have the OSMC remote configured and working although in practice it is rarely used. However this small footprint radio like endpoint has two encoders, and 10 push buttons which are monitored by an Arduino UNO R4. That reads those inputs and uses web calls to a custom Roon extension that uses the RoonAPI to control play/pause/stop, volume and select radio stations from Roon live radio for the RoPieee endpoint.

But it is easy to distinguish any play/pause stop events triggered by this custom extension in the Roon logs as these events are preceded by the API call.

So Triggered by Arduino

04/21 21:49:32 [Local 04/21 22:49:32] Trace: [roonapi] [apiclient 192.168.1.103:34812] GOT com.roonlabs.transport:2/control {“zone_or_output_id”:“17012544785f8b36a95b4cf93ed846179af7”,“control”:“play”}
04/21 21:49:32 [Local 04/21 22:49:32] Trace: [zone KitchenPi] Play

triggered by RoPieee touch screen
04/21 21:44:31 [Local 04/21 22:44:31] Trace: [roonapi] [apiclient 192.168.1.176:43032] GOT com.roonlabs.transport:2/control {“zone_or_output_id”:“16012544785f8b36a95b4cf93ed846179af7”,“control”:“playpause”}
04/21 21:44:31 [Local 04/21 22:44:31] Trace: [zone KitchenPi] PlayPause

But when I get an unexplained pause leading to a stop all I see is
[zone KitchenPi] Pause

With no preceding api reference which suggests to me it is a Roon RAAT instruction but I know none has been issued by a user as I am the only Roon user, the Ropieee end point being controlled via the touch screen or the Arduino. And the only issue I see is this play/pause one. I don’t get any unexpected changes to volume or source just play/pause. Hope that is maybe a clearer explanation of what I am seeing. Thanks again

I can be more specific. In the Roon logs I have shared in post events showing like this

04/21 08:33:05 \[Local 04/21 09:33:05\] Trace: \[zone KitchenPi\] Pause

Are the unexplained ones as NO api call precedes them so this suggests a RAAT request to pause was made at this specific time and my question is did it come from the RoPieee. If so what triggered it within RoPieee? If not this suggests something is happening outside of RoPieee within the Roon environment

Hi @spockfish
Just to follow up still seeing these issues. I have sent another RoPieee log with this identifier 66ad393111cf8ae7
@noris also contacted me to say Roon had made some updates to Live Radio to improve stability but after updating I have seen no improvement to the situation with the RoPieee end point.
Roon log entries
1st instance
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [MacBook Air M1] [Inactive] [STOPPED @ 0:00]
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [zone MacBook Air M1] Loaded Queue=10 Tracks Swim=Inactive AutoSwim=True Loop=Disabled Shuffle=False
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [MacBook Air M1] [Inactive] [PAUSED @ 3:28/3:43] Iceberg - 10cc
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [zone KitchenPi] Pause
04/29 08:33:04 [Local 04/29 09:33:04] Info: [zone KitchenPi] OnPlayFeedback Stopped
04/29 08:33:04 [Local 04/29 09:33:04] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 08:33:04 [Local 04/29 09:33:04] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [KitchenPi] [LowQuality, 24/48 AAC => 24/48] [STOPPED @ 0:00] Bob Marley & The Wailers - Could You Be Loved
04/29 08:33:04 [Local 04/29 09:33:04] Info: [audio/env] [zoneplayer → stream] All streams were disposed
04/29 08:33:04 [Local 04/29 09:33:04] Info: [audio/env] [zoneplayer → stream → endpoint] All streams were disposed
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [KitchenPi] [zoneplayer/raat] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [IQaudIODAC] [raatclient] SENT [12]{“request”:“end_stream”}
04/29 08:33:04 [Local 04/29 09:33:04] Debug: [raat/tcpaudiosource] disconnecting
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [MacBook Air Speakers] [raatclient] GOT [5] {“action”:“Changed”,“controls”:{“volume”:{“type”:“number”,“info”:{“config”:{“type”:“coreaudio”,“device”:“BuiltInSpeakerDevice”}},“value”:100,“max”:100.0,“step”:1.0,“mute”:false,“min”:0.0}},“status”:“Success”}
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [IQaudIODAC] [raatclient] GOT [10] {“status”:“Ended”}
04/29 08:33:04 [Local 04/29 09:33:04] Info: [audio/env] [zoneplayer] All streams were disposed
04/29 08:33:04 [Local 04/29 09:33:04] Trace: [IQaudIODAC] [raatclient] GOT [12] {“status”:“Success”}
04/29 08:33:04 [Local 04/29 09:33:04] Info: sleep 39ms after flush
2nd instance
04/29 08:43:45 [Local 04/29 09:43:45] Trace: [KitchenPi] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 10:37] Taylor Swift - Elizabeth Taylor
04/29 08:43:47 [Local 04/29 09:43:47] Debug: [easyhttp] [12662] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 380 ms, status code: 200, request body size: 0 B
04/29 08:43:48 [Local 04/29 09:43:48] Debug: [easyhttp] [12663] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio%3D320000.norewind.m3u8 returned after 262 ms, status code: 200, request body size: 0 B
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [HiFi Altair] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 163:44] Taylor Swift - Elizabeth Taylor
04/29 08:43:48 [Local 04/29 09:43:48] Debug: [easyhttp] [12664] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio=320000-277726913.ts returned after 247 ms, status code: 200, request body size: 0 B
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [zone KitchenPi] Pause
04/29 08:43:48 [Local 04/29 09:43:48] Info: [zone KitchenPi] OnPlayFeedback Stopped
04/29 08:43:48 [Local 04/29 09:43:48] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 08:43:48 [Local 04/29 09:43:48] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [KitchenPi] [LowQuality, 24/48 AAC => 24/48] [STOPPED @ 0:00] Taylor Swift - Elizabeth Taylor
04/29 08:43:48 [Local 04/29 09:43:48] Info: [audio/env] [zoneplayer → stream] All streams were disposed
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [KitchenPi] [zoneplayer/raat] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/29 08:43:48 [Local 04/29 09:43:48] Info: [audio/env] [zoneplayer → stream → endpoint] All streams were disposed
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [IQaudIODAC] [raatclient] SENT [15]{“request”:“end_stream”}
04/29 08:43:48 [Local 04/29 09:43:48] Debug: [raat/tcpaudiosource] disconnecting
04/29 08:43:48 [Local 04/29 09:43:48] Info: [audio/env] [zoneplayer] All streams were disposed
04/29 08:43:48 [Local 04/29 09:43:48] Debug: [easyhttp] [12665] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& canceled after 233 ms
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [IQaudIODAC] [raatclient] GOT [13] {“status”:“Ended”}
04/29 08:43:48 [Local 04/29 09:43:48] Trace: [IQaudIODAC] [raatclient] GOT [15] {“status”:“Success”}
04/29 08:43:48 [Local 04/29 09:43:48] Info: sleep 39ms after flush

3rd Instance
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [KitchenPi] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 26:25] Melanie C - Undefeated Champion
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [HiFi Altair] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 190:14] Melanie C - Undefeated Champion
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [zone KitchenPi] Pause
04/29 09:10:18 [Local 04/29 10:10:18] Info: [zone KitchenPi] OnPlayFeedback Stopped
04/29 09:10:18 [Local 04/29 10:10:18] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 09:10:18 [Local 04/29 10:10:18] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [KitchenPi] [LowQuality, 24/48 AAC => 24/48] [STOPPED @ 0:00] Melanie C - Undefeated Champion
04/29 09:10:18 [Local 04/29 10:10:18] Info: [audio/env] [zoneplayer → stream] All streams were disposed
04/29 09:10:18 [Local 04/29 10:10:18] Info: [audio/env] [zoneplayer → stream → endpoint] All streams were disposed
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [KitchenPi] [zoneplayer/raat] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [IQaudIODAC] [raatclient] SENT [18]{“request”:“end_stream”}
04/29 09:10:18 [Local 04/29 10:10:18] Debug: [raat/tcpaudiosource] disconnecting
04/29 09:10:18 [Local 04/29 10:10:18] Info: [audio/env] [zoneplayer] All streams were disposed
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [IQaudIODAC] [raatclient] GOT [16] {“status”:“Ended”}
04/29 09:10:18 [Local 04/29 10:10:18] Trace: [IQaudIODAC] [raatclient] GOT [18] {“status”:“Success”}
04/29 09:10:18 [Local 04/29 10:10:18] Info: sleep 39ms after flush
04

4th Instance
04/29 09:16:39 [Local 04/29 10:16:39] Trace: [HiFi Altair] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 196:35] Vernon Kay - Good News Wednesday
04/29 09:16:39 [Local 04/29 10:16:39] Debug: [easyhttp] [14778] GET to https://as-hls-uk-live.akamaized.net/pool_74208725/live/uk/bbc_radio_two/bbc_radio_two.isml/bbc_radio_two-audio=320000-277727221.ts returned after 94 ms, status code: 200, request body size: 0 B
04/29 09:16:41 [Local 04/29 10:16:41] Debug: [easyhttp] [14779] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 178 ms, status code: 200, request body size: 0 B
04/29 09:16:42 [Local 04/29 10:16:42] Debug: [easyhttp] [14780] GET to https://api.roonlabs.net/internetradio/2/api/stations/7c828d71-6de4-4a86-962f-6f5c0b5e2072/metadata?format=msgpack&wait=True&timestamp=-1& returned after 194 ms, status code: 200, request body size: 0 B
04/29 09:16:42 [Local 04/29 10:16:42] Trace: [zone KitchenPi] Pause
04/29 09:16:42 [Local 04/29 10:16:42] Info: [zone KitchenPi] OnPlayFeedback Stopped
04/29 09:16:42 [Local 04/29 10:16:42] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 09:16:42 [Local 04/29 10:16:42] Info: [zone KitchenPi] Canceling Pending Sleep
04/29 09:16:42 [Local 04/29 10:16:42] Trace: [KitchenPi] [LowQuality, 24/48 AAC => 24/48] [STOPPED @ 0:00] Vernon Kay - Good News Wednesday
04/29 09:16:42 [Local 04/29 10:16:42] Info: [audio/env] [zoneplayer → stream] All streams were disposed
04/29 09:16:42 [Local 04/29 10:16:42] Info: [audio/env] [zoneplayer → stream → endpoint] All streams were disposed
04/29 09:16:42 [Local 04/29 10:16:42] Trace: [KitchenPi] [zoneplayer/raat] Endpoint IQaudIODAC State Changed: Playing => Prepared
04/29 09:16:42 [Local 04/29 10:16:42] Trace: [IQaudIODAC] [raatclient] SENT [21]{“request”:“end_stream”}
04/29 09:16:42 [Local 04/29 10:16:42] Debug: [raat/tcpaudiosource] disconnecting
04/29 09:16:42 [Local 04/29 10:16:42] Info: [audio/env] [zoneplayer] All streams were disposed
04/29 09:16:42 [Local 04/29 10:16:42] Trace: [IQaudIODAC] [raatclient] GOT [19] {“status”:“Ended”}
04/29 09:16:42 [Local 04/29 10:16:42] Trace: [IQaudIODAC] [raatclient] GOT [21] {“status”:“Success”}
04/29 09:16:42 [Local 04/29 10:16:42] Info: sleep 39ms after flush

So these date time should tie up with the RoPieee logs which hoepfully might give some clue as to what is happening.

I have also set up another end point to run radio 2 constantly. This is an Auralic Altair G2 connected on WiFi rather than ethernet. This is not showing any pause events which tends to suggest it is something around the RoPieee end point specifically.
Thanks as ever for your help

Well, I see the ‘stopped’ Roon state being reflected, but the logs suggest that this is done server side.

I would suggest the following:

  • remove the OSMC from the RoPieee unit
  • disable the screen on the RoPieee unit (you can do that from the ‘advanced’ tab, by setting ‘Touchscreen Type’ to ‘off’.

Try again. At least with this we know for sure that there isn’t anything on the RoPieee that can influence Roon.

Thanks @spockfish
I am away from early tomorrow for about 10 days so will try this on my return

1 Like

Hi @spockfish
My ferry got delayed (its a Scotttish thing right now) so I have been able to remove the OSMC dongle and test but that has made no difference. As I have mentioned before it does often happen when I walk into the room and I assumed that it was my phone (could still be related). So I have tried not taking my phone with me but it still can happen. I also tried removing my smart watch - still happens sometimes. I am now wondering if it is my hearing aids (which have BT and BT lte).
I have managed to replicate a stop 4 times now when I walk into the room and the only device I have on me is my hearing aids and it is a definite stop as soon as I am within around 2m of the RoPieee… Conversely when not wearing the hearing aids I have had no stops.
I know it is a very limited sample and I know you haven’t implemented bluetooth as one of the services but as Raspberry Pis have bluetooth is it possible this is interfering in some way? Or is bluetooth actively blocked in RoPieee? May well be clutching at straws here and will try disabling the screen on my return. But it woulkd explain why it happems so often as I guess any mobile bluetooth device could be triggering it.I should also confirm I still have Aitplay diesabled - indeed all items under the services tab are disabled.

Bluetooth is even’t running on a RoPieee.

is there any chance you can test this with RAAT instead of Airplay?

@spockfish I am using RAAT - as I said Airplay is disabled

Ok, then maybe change the title of the topic, as this is not related to airplay.

Fair pint @spockfish . Have changed it to something as I really don’t know what. From what you have said then Bluetooth is actively disabled within the RoPieee OS?

Since the new Roon Bridge I’ve been having more issues with buffering and pauses in music (not specific to Ropieee from what I can tell). Is it possible Roon is pausing because it thinks there’s been an interruption in traffic?

I don’t see anything in the logs to confirm that @James_Fitzell

yes. There is nothing that can interupt Roon from RoPieee’s side, except the remote control (which is active when running a display and/or remote control).