Roon Playback stopped/stuck with 1 second to finish the song

Support
1

Core Machine (Operating system/System info/Roon build number)

NUC 10, UBUNTU 20.10, Roon 1.7.667

Network Details (Including networking gear model/manufacturer and if on WiFi/Ethernet)

Wired network

Audio Devices (Specify what device you’re using and its connection type - USB/HDMI/etc.)

Ropieeexl->USB->Pro-Ject S2

Description Of Issue

While playing a song, the playback stops got stuck at one second to the end of the song. It appears in roon client that song is still playing, but nothing is changing (the remaining timer, no sound). It only happens occasionally on different songs. If I click on pause and resume the song will complete. If I click on next the next song will play etc.

Here is the extract from the roon logs where you can see that the playback was stuck on one second to complete (between 11:25:11 and 11:28:23) :

12/11 11:24:46 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2919,"seek_position":267}]}
12/11 11:24:47 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:28/4:33] Indiscipline - King Crimson
12/11 11:24:47 Trace: [roonapi] [apiclient 192.168.0.73:47652] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2918,"seek_position":268}]}
12/11 11:24:47 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2918,"seek_position":268}]}
12/11 11:24:48 Trace: [roonapi] [apiclient 192.168.0.73:47652] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2917,"seek_position":269}]}
12/11 11:24:48 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2917,"seek_position":269}]}
12/11 11:24:49 Trace: [roonapi] [apiclient 192.168.0.73:47652] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2916,"seek_position":270}]}
12/11 11:24:49 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2916,"seek_position":270}]}
12/11 11:24:50 Trace: [roonapi] [apiclient 192.168.0.73:47652] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2915,"seek_position":271}]}
12/11 11:24:50 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2915,"seek_position":271}]}
12/11 11:24:51 Trace: [roonapi] [apiclient 192.168.0.73:47652] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2914,"seek_position":272}]}
12/11 11:24:51 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"zones_seek_changed":[{"zone_id":"1601e9eeb72410d991de2017717434ba64f9","queue_time_remaining":2914,"seek_position":272}]}
12/11 11:24:53 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:24:56 Info: [stats] 4962mb Virtual, 890mb Physical, 361mb Managed, 0 Handles, 78 Threads
12/11 11:25:04 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"message":"Running (12)","is_error":false}
12/11 11:25:04 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2666572058414 rtt=1000us offset=-9627152941us delta=-184us drift=-7437us in 548.446s (-13.561ppm, -48.821ms/hr)
12/11 11:25:11 Info: [stats] 4962mb Virtual, 890mb Physical, 362mb Managed, 0 Handles, 78 Threads
12/11 11:25:23 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:25:26 Info: [stats] 4962mb Virtual, 890mb Physical, 363mb Managed, 0 Handles, 78 Threads
12/11 11:25:32 Trace: [broker/accounts] [heartbeat] now=12/11/2020 11:25:32 nextauthrefresh=12/11/2020 12:00:33 nextmachineallocate=12/11/2020 15:00:32
12/11 11:25:35 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2696811574515 rtt=1000us offset=-9627153425us delta=243us drift=-7921us in 578.6865s (-13.689ppm, -49.280ms/hr)
12/11 11:25:37 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:25:41 Info: [stats] 4962mb Virtual, 890mb Physical, 365mb Managed, 0 Handles, 78 Threads
12/11 11:25:53 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:25:56 Info: [stats] 4962mb Virtual, 890mb Physical, 368mb Managed, 0 Handles, 78 Threads
12/11 11:26:01 Debug: [easyhttp] [283] POST to https://swim.roonlabs.net/1/session/ceb72d9846e34856b25a24d329218555/feedback returned after 281 ms, status code: 200
12/11 11:26:04 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"message":"Running (13)","is_error":false}
12/11 11:26:05 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2726997974461 rtt=1500us offset=-9627154025us delta=-591us drift=-8521us in 608.8725s (-13.996ppm, -50.384ms/hr)
12/11 11:26:08 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:26:11 Info: [stats] 4962mb Virtual, 890mb Physical, 369mb Managed, 0 Handles, 78 Threads
12/11 11:26:23 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:26:26 Info: [stats] 4962mb Virtual, 890mb Physical, 372mb Managed, 0 Handles, 78 Threads
12/11 11:26:35 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2757199422311 rtt=1000us offset=-9627154577us delta=-164us drift=-9073us in 639.075s (-14.198ppm, -51.114ms/hr)
12/11 11:26:41 Info: [stats] 4962mb Virtual, 890mb Physical, 373mb Managed, 0 Handles, 78 Threads
12/11 11:26:53 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:26:56 Info: [stats] 4962mb Virtual, 890mb Physical, 374mb Managed, 0 Handles, 78 Threads
12/11 11:27:04 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"message":"Running (14)","is_error":false}
12/11 11:27:05 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2787401293210 rtt=1500us offset=-9627154706us delta=-772us drift=-9202us in 669.2775s (-13.750ppm, -49.501ms/hr)
12/11 11:27:11 Info: [stats] 4962mb Virtual, 890mb Physical, 374mb Managed, 0 Handles, 78 Threads
12/11 11:27:14 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:27:22 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:27:26 Info: [stats] 4962mb Virtual, 890mb Physical, 378mb Managed, 0 Handles, 78 Threads
12/11 11:27:35 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2817615216914 rtt=1500us offset=-9627154783us delta=-261us drift=-9279us in 699.4905s (-13.266ppm, -47.756ms/hr)
12/11 11:27:41 Info: [stats] 4962mb Virtual, 890mb Physical, 380mb Managed, 0 Handles, 78 Threads
12/11 11:27:46 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:27:52 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:27:56 Info: [stats] 4962mb Virtual, 890mb Physical, 383mb Managed, 0 Handles, 78 Threads
12/11 11:28:04 Trace: [roonapi] [apiclient 192.168.0.70:59620] CONTINUE Changed {"message":"Running (15)","is_error":false}
12/11 11:28:06 Trace: [Pro-Ject S2] [zoneplayer/raat] sync Pre Box S2 Digital: realtime=2847806637710 rtt=1500us offset=-9627155362us delta=-225us drift=-9858us in 729.6835s (-13.510ppm, -48.638ms/hr)
12/11 11:28:11 Info: [stats] 4962mb Virtual, 890mb Physical, 385mb Managed, 0 Handles, 78 Threads
12/11 11:28:23 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [PLAYING @ 4:32/4:33] Indiscipline - King Crimson
12/11 11:28:26 Info: [stats] 4962mb Virtual, 890mb Physical, 386mb Managed, 0 Handles, 78 Threads
12/11 11:28:28 Trace: [zone Pro-Ject S2] Next
12/11 11:28:28 Info: [library] recorded play for profile aedb201d-e179-48a7-bbcf-ee176920a45c: mediaid=202:0:62423673 metadataid= contentid=202:0:62423673 libraryid= isfromswim=False
12/11 11:28:28 Info: [zone Pro-Ject S2] OnPlayFeedback Stopped
12/11 11:28:28 Info: [zone Pro-Ject S2] Canceling Pending Sleep
12/11 11:28:28 Info: [Pro-Ject S2] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
12/11 11:28:28 Debug: [zone Pro-Ject S2] _Advance
12/11 11:28:28 Trace: [zone Pro-Ject S2] Selecting Source state=Stopped
12/11 11:28:28 Trace: [Pro-Ject S2] [zoneplayer/raat] Endpoint Pre Box S2 Digital State Changed: Playing => Prepared
12/11 11:28:28 Trace: [Pre Box S2 Digital] [raatclient] SENT [52]{"request":"end_stream"}
12/11 11:28:28 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
12/11 11:28:28 Debug: [raat/tcpaudiosource] disconnecting
12/11 11:28:28 Info: [audio/env] [zoneplayer] All streams were disposed
12/11 11:28:28 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
12/11 11:28:28 Trace: [Pro-Ject S2] [Lossless, 24/44 QOBUZ FLAC => 24/44] [5% buf] [LOADING @ 0:00] Curtains - Peter Hammill
1 Like
3

Hi @Esad_Brnicanin,

Thanks for reaching out and for including that log set!

Can you please confirm if you are using the latest firmware on your Pro-ject / Ropieee zone?

Are you able to reproduce this issue if you temporarily connect the Pro-Ject directly to the Core?

4

It is the latest ropieee build. It only happened twice in the last couple of months and I cannot reproduce it on demand.

5

Hi @Esad_Brnicanin,

Thanks for the additional details. If the issue re-occurs, can you please let us know and submit a new log set? I would also be curious to know if this issue occurs on Local content or just on Qobuz content, so if you do notice a pattern, please let me know!

6

it never occured on the local content. I think the pattern is (not 100% sure) :

  1. Roon radio on
  2. the Roon radio playback content is switching from Tidal HiRes to Qobuz (24/192)
  3. Qobuz 24/192 playback fails for the song (and sometimes for the next few songs if they are Qobuz 24/192).
  4. Replaying failed songs is OK

I have now completelly rewired my living room, so no wifi in the roon playback. I will keep an eye on this.

Esad

1 Like
7

Hi @Esad_Brnicanin,

Thanks for those additional details!

If this issue is happening between sample rate switches, is there any change in behavior if you try to resample everything to the same sample rate via DSP?

Max Sample Rate DSP
Max Sample Rate DSP985×719 56.9 KB

Let me know if that helps and Happy Holidays!

8

Since I wired the roon end point 2 weeks ago, I cannot reproduce the issue. I’ll keep an eye on this, but it looks way better now.

E

1 Like
9

Thanks for the update @Esad_Brnicanin, if the issue re-occur please just let us know and we can take another look!

10

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

12

This is the continuation of the topic (now closed) :


So the problem occured again. This time it was with different roon point.
In short the song has stopped playing with 1 sec remaining to finish the song.
The Roon core is on NUC 10 (16GB ram, 1TB SSD, i9 CPU - 12core) with ubuntu server 20.04 and roon . This is a full wired network (1GB network, Broadband on 600Mbps) both for roon core and roon end point. End point in this case is a local iMac . I was playing a QOBUZ song 24bit/192kHz. No DSP, only volume leveling in the output.

this is the log file content from when the song has stopped playing until I moved to the next song:

01/11 15:44:06 Trace: [MacOutput] [zoneplayer/raat] sync System Output: realtime=1433210857393 rtt=500us offset=128158857us delta=198us drift=10788us in 1065.7335s (10.123ppm, 36.442ms/hr)
01/11 15:44:13 Info: [stats] 4983mb Virtual, 1078mb Physical, 523mb Managed, 0 Handles, 79 Threads
01/11 15:44:20 Trace: [roonapi] [apiclient 192.168.0.69:49962] CONTINUE Changed {"message":"Running (23)","is_error":false}
01/11 15:44:21 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:44:28 Info: [stats] 4983mb Virtual, 1078mb Physical, 524mb Managed, 0 Handles, 79 Threads
01/11 15:44:35 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:44:36 Trace: [MacOutput] [zoneplayer/raat] sync System Output: realtime=1463352265451 rtt=500us offset=128157265us delta=458us drift=9196us in 1095.8765s (8.392ppm, 30.210ms/hr)
01/11 15:44:43 Info: [stats] 4983mb Virtual, 1078mb Physical, 524mb Managed, 0 Handles, 79 Threads
01/11 15:44:51 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:44:56 Trace: [push] restarting connection (Connection timed out)
01/11 15:44:56 Trace: [push] retrying connection in 61419ms
01/11 15:44:58 Info: [stats] 4983mb Virtual, 1078mb Physical, 525mb Managed, 0 Handles, 78 Threads
01/11 15:45:03 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:45:06 Trace: [MacOutput] [zoneplayer/raat] sync System Output: realtime=1493527100033 rtt=500us offset=128155100us delta=-505us drift=7030us in 1126.054s (6.244ppm, 22.477ms/hr)
01/11 15:45:13 Info: [stats] 4983mb Virtual, 1078mb Physical, 526mb Managed, 0 Handles, 78 Threads
01/11 15:45:20 Trace: [roonapi] [apiclient 192.168.0.69:49962] CONTINUE Changed {"message":"Running (24)","is_error":false}
01/11 15:45:21 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:45:28 Info: [stats] 4983mb Virtual, 1078mb Physical, 526mb Managed, 0 Handles, 78 Threads
01/11 15:45:36 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:45:37 Trace: [MacOutput] [zoneplayer/raat] sync System Output: realtime=1523690290383 rtt=500us offset=128154290us delta=-87us drift=6221us in 1156.2175s (5.381ppm, 19.370ms/hr)
01/11 15:45:44 Info: [stats] 4983mb Virtual, 1078mb Physical, 526mb Managed, 0 Handles, 78 Threads
01/11 15:45:51 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:45:58 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
01/11 15:45:58 Trace: [push] connecting to 34.74.254.52:9200
01/11 15:45:58 Trace: [push] connected
01/11 15:45:59 Info: [stats] 4983mb Virtual, 1078mb Physical, 528mb Managed, 0 Handles, 80 Threads
01/11 15:46:07 Trace: [MacOutput] [zoneplayer/raat] sync System Output: realtime=1553853199308 rtt=500us offset=128153199us delta=-119us drift=5129us in 1186.3825s (4.324ppm, 15.567ms/hr)
01/11 15:46:12 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:14 Info: [stats] 4985mb Virtual, 1080mb Physical, 530mb Managed, 0 Handles, 80 Threads
01/11 15:46:20 Trace: [roonapi] [apiclient 192.168.0.69:49962] CONTINUE Changed {"message":"Running (25)","is_error":false}
01/11 15:46:21 Trace: [MacOutput] [HighQuality 18.2x, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:29 Info: [stats] 4985mb Virtual, 1081mb Physical, 531mb Managed, 0 Handles, 80 Threads
01/11 15:46:29 Trace: [zone MacOutput] PlayPause
01/11 15:46:29 Trace: [zone MacOutput] Pause
01/11 15:46:29 Info: [zone MacOutput] Canceling Pending Sleep
01/11 15:46:29 Trace: [MacOutput] [zoneplayer/raat] Pausing at streamtime_ns 1010125652604 now_ns 1576132751759
01/11 15:46:29 Trace: [MacOutput] [HighQuality, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:29 Trace: [System Output] [raatclient] SENT [11]{"request":"stop"}
01/11 15:46:29 Trace: [MacOutput] [zoneplayer/raat] Endpoint System Output State Changed: Playing => Paused
01/11 15:46:29 Info: [zone MacOutput] OnPlayFeedback Paused
01/11 15:46:29 Trace: [MacOutput] [HighQuality, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PAUSED @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:29 Trace: [System Output] [raatclient] GOT [7] {"status":"Stopped"}
01/11 15:46:29 Trace: [System Output] [raatclient] GOT [11] {"status":"Success"}
01/11 15:46:29 Trace: [roonapi] [apiclient 192.168.0.73:36080] CONTINUE Changed {"zones_changed":[{"zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","display_name":"MacOutput","outputs":[{"output_id":"1701bf2f19077a2686dbf5b457a59352fda8","zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","can_group_with_output_ids":["1701bf2f19077a2686dbf5b457a59352fda8","1701e9eeb72410d991de2017717434ba64f9"],"display_name":"MacOutput","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":"System Output","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":13,"queue_time_remaining":3178,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":286,"length":287,"one_line":{"line1":"Salt of the Earth - The Rolling Stones"},"two_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones"},"three_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones","line3":"Beggars Banquet"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3850e03084a41aeaa273509da5b041b7568b1901362968d11454a7a0222ad5603603212a7a072bd54054230bc46552d5b3e85a84cfb8e39dab858a59dcb505989a3","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbe114ea2a6922614529916580561ec7561249f743fd0c6ef4854d3a7f5c1a1a91d8592aab2c0252b2fbdac33c2e13d83f"]}}]}
01/11 15:46:29 Trace: [roonapi] [apiclient 192.168.0.69:49962] CONTINUE Changed {"zones_changed":[{"zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","display_name":"MacOutput","outputs":[{"output_id":"1701bf2f19077a2686dbf5b457a59352fda8","zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","can_group_with_output_ids":["1701bf2f19077a2686dbf5b457a59352fda8","1701e9eeb72410d991de2017717434ba64f9"],"display_name":"MacOutput","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":"System Output","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":13,"queue_time_remaining":3178,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":286,"length":287,"one_line":{"line1":"Salt of the Earth - The Rolling Stones"},"two_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones"},"three_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones","line3":"Beggars Banquet"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3850e03084a41aeaa273509da5b041b7568b1901362968d11454a7a0222ad5603603212a7a072bd54054230bc46552d5b3e85a84cfb8e39dab858a59dcb505989a3","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbe114ea2a6922614529916580561ec7561249f743fd0c6ef4854d3a7f5c1a1a91d8592aab2c0252b2fbdac33c2e13d83f"]}}]}
01/11 15:46:31 Trace: [zone MacOutput] PlayPause
01/11 15:46:31 Trace: [zone MacOutput] Unpause
01/11 15:46:31 Trace: [MacOutput] [zoneplayer/raat] wait for ready in 0ms
01/11 15:46:31 Trace: [MacOutput] [zoneplayer/raat] Adjusting playback start offset from 50ms to 206ms
01/11 15:46:31 Info: [zone MacOutput] OnPlayFeedback Playing
01/11 15:46:31 Trace: [MacOutput] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
01/11 15:46:31 Trace: [MacOutput] [HighQuality, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:31 Trace: [musicpowerstate] music is playing, preventing idle sleep
01/11 15:46:31 Trace: [System Output] [raatclient] SENT [12]{"request":"start","min_offset":206000000,"stream_sample":57978259}
01/11 15:46:31 Trace: [MacOutput] [zoneplayer/raat] Endpoint System Output State Changed: Paused => Playing
01/11 15:46:31 Trace: [System Output] [raatclient] GOT [7] {"status":"Playing"}
01/11 15:46:31 Trace: [System Output] [raatclient] GOT [12] {"status":"Success","time":1578365900681}
01/11 15:46:31 Trace: [roonapi] [apiclient 192.168.0.73:36080] CONTINUE Changed {"zones_changed":[{"zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","display_name":"MacOutput","outputs":[{"output_id":"1701bf2f19077a2686dbf5b457a59352fda8","zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","can_group_with_output_ids":["1701bf2f19077a2686dbf5b457a59352fda8","1701e9eeb72410d991de2017717434ba64f9"],"display_name":"MacOutput","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":"System Output","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":13,"queue_time_remaining":3178,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":286,"length":287,"one_line":{"line1":"Salt of the Earth - The Rolling Stones"},"two_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones"},"three_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones","line3":"Beggars Banquet"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3850e03084a41aeaa273509da5b041b7568b1901362968d11454a7a0222ad5603603212a7a072bd54054230bc46552d5b3e85a84cfb8e39dab858a59dcb505989a3","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbe114ea2a6922614529916580561ec7561249f743fd0c6ef4854d3a7f5c1a1a91d8592aab2c0252b2fbdac33c2e13d83f"]}}]}
01/11 15:46:31 Trace: [roonapi] [apiclient 192.168.0.69:49962] CONTINUE Changed {"zones_changed":[{"zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","display_name":"MacOutput","outputs":[{"output_id":"1701bf2f19077a2686dbf5b457a59352fda8","zone_id":"1601bf2f19077a2686dbf5b457a59352fda8","can_group_with_output_ids":["1701bf2f19077a2686dbf5b457a59352fda8","1701e9eeb72410d991de2017717434ba64f9"],"display_name":"MacOutput","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":"System Output","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":13,"queue_time_remaining":3178,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":286,"length":287,"one_line":{"line1":"Salt of the Earth - The Rolling Stones"},"two_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones"},"three_line":{"line1":"Salt of the Earth","line2":"The Rolling Stones","line3":"Beggars Banquet"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3850e03084a41aeaa273509da5b041b7568b1901362968d11454a7a0222ad5603603212a7a072bd54054230bc46552d5b3e85a84cfb8e39dab858a59dcb505989a3","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbe114ea2a6922614529916580561ec7561249f743fd0c6ef4854d3a7f5c1a1a91d8592aab2c0252b2fbdac33c2e13d83f"]}}]}
01/11 15:46:31 Trace: [System Output] [raatclient] GOT [7] {"status":"Dropout","samples":4722}
01/11 15:46:37 Trace: [MacOutput] [zoneplayer/raat] sync System Output: realtime=1584207620823 rtt=500us offset=128151620us delta=17us drift=3551us in 1216.738s (2.919ppm, 10.508ms/hr)
01/11 15:46:39 Trace: [MacOutput] [HighQuality, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:44 Info: [stats] 4985mb Virtual, 1081mb Physical, 531mb Managed, 0 Handles, 80 Threads
01/11 15:46:44 Trace: [zone MacOutput] Next
01/11 15:46:44 Info: [library] recorded play for profile aedb201d-e179-48a7-bbcf-ee176920a45c: mediaid=202:0:55976381 metadataid= contentid=202:0:55976381 libraryid= isfromswim=False
01/11 15:46:44 Info: [zone MacOutput] OnPlayFeedback Stopped
01/11 15:46:44 Info: [zone MacOutput] Canceling Pending Sleep
01/11 15:46:44 Debug: [zone MacOutput] _Advance
01/11 15:46:44 Trace: [zone MacOutput] Selecting Source state=Stopped
01/11 15:46:44 Info: [MacOutput] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
01/11 15:46:44 Trace: [MacOutput] [HighQuality, 24/192 QOBUZ FLAC => 24/48] [2% buf] [PLAYING @ 4:46/4:47] Salt of the Earth - The Rolling Stones
01/11 15:46:44 Info: [audio/env] [zoneplayer] All streams were disposed
01/11 15:46:44 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/11 15:46:44 Trace: [MacOutput] [zoneplayer/raat] Endpoint System Output State Changed: Playing => Prepared
01/11 15:46:44 Trace: [System Output] [raatclient] SENT [13]{"request":"end_stream"}
01/11 15:46:44 Debug: [raat/tcpaudiosource] disconnecting
01/11 15:46:44 Trace: [System Output] [raatclient] GOT [7] {"status":"Ended"}
01/11 15:46:44 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/11 15:46:44 Trace: [System Output] [raatclient] GOT [13] {"status":"Success"}
01/11 15:46:44 Trace: [MacOutput] [HighQuality, 24/192 QOBUZ FLAC => 24/48] [2% buf] [LOADING @ 0:00] Sad Sad Sad - The Rolling Stones
1 Like
13

I thought I had read that a work-around is to click “Play All” although I have never seen that “button.” (I have the same occurrence…song starts, skips to the next and/or near the end of a track, it jumps to the next. I have this with Qobuz. )

14

Hi @Esad_Brnicanin,

I’ve merged your two threads and re-opened this case!
Let me get this log snippet over to the team for further review.

Can you please provide some more details regarding the network setup?

What is the model/manufacturer of your router and any switches, range extenders, powerline adapters, etc?

15

The router is Virgin Media Hub 3 (VMDG505) .
There is an unmanaged switch between router and the roon core. The model of the switch is TP-Link TL-SG1005D.
The roon end point in the last case when the issue happened was imac which was directly connected to the router via cable. Roon core and roon end points are on the static IP addresses. Everything is wired with the Cat6 cables.
E

1 Like
16

Hi @Esad_Brnicanin,

Thanks for those additional details!

I spoke to the team regarding your logs and it looks like there was a dropout in the second set and the track didn’t buffer properly.

Since this issue is happening fairly rarely, it’s hard to say what is triggering it, it may be a temporary network issue.

If you notice the issue reproducing more often, please let us know more timestamps and we can take another look!