Suddenly Roon on Qnap hangs when playing m4a files

Haim_Ashkenazi · October 25, 2020, 11:54am

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

Roon 1.7 (build 667) on Qnap and on mac/windows (same version)

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

WIFI local network

Description Of Issue

Hi,

Recently I moved my database from Mac to my new Qnap server because I wanted to connect to it from several laptops. The database contains both my flac files and my itunes m4a files. This worked reasonably well (although there are certainly quirks). Today I tried to listen to some music and it didn’t play. It was a little tricky to pin point what happens because my windows client didn’t play but my mac did (different album though). It took me a while and several restarts to figure it out. Regardless of the client I connect from, if I try to play m4a file (from my iTunes folder) it just hangs and does not play anything on this client until I restart Roon on the Qnap server (not the client - that doesn’t help). By hangs I mean it seems like playing but the time line stays on 0:00 - it does not advance. As long as I don’t play any m4a file everything works. I already played m4a files with this setup but I can’t tell whether it was before or after the last update.

Please help

Thanks in advance

Haim Ashkenazi

Haim_Ashkenazi · October 25, 2020, 3:54pm

@support, please help.

Haim_Ashkenazi · October 26, 2020, 1:35pm

@support, Please help. This affects half of my library

Haim_Ashkenazi · October 26, 2020, 6:16pm

I’m adding relevant logs (RoonServer.log.txt, the RAATServer didn’t produce logs during my tests) for playback of m4a file and flac file.

Playing M4a file:

10/26 19:54:15 Info: [stats] 3129mb Virtual, 776mb Physical, 213mb Managed, 0 Handles, 88 Threads


10/26 19:54:24 Info: [library/albumdetails] Created album details screen for LibraryAlbum[11823, Benjamin Britten, Mstislav Rostropovich - Schubert: Arpeggione Sonata; Schumann: Fünf Stücke im Volkston; Debussy: Cello Sonata]
10/26 19:54:24 Info: [library/albumdetails] 79004d5730303031393430383235, 79004d5230303032373231343837, 79004d5230303034333836323436, 3e01561b39043419064e806cd2835d393c37, 3e013238c7dc1b86e44fb20926f95789b9dd, 79004d5230303034303034393739, a60034353539393533, a60034353432353930, a6003232373234343336, c80030303032383934383130323537, c80030303032383934373538323339, c80030303032383934363039373432, be0031303335353234, be003136353337343139
10/26 19:54:24 Trace: [music/query] performing album query
10/26 19:54:24 Trace: [music/query] query returned 6 Sooloos.Broker.Api.AlbumLite(s)
10/26 19:54:24 Trace: [music/query] performing album query
10/26 19:54:24 Trace: [music/query] query returned 0 Sooloos.Broker.Api.AlbumLite(s)
10/26 19:54:24 Debug: [easyhttp] [30] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il returned after 197 ms, status code: 200
10/26 19:54:24 Info: [library/albumdetails] found 1 streaming service alternates
10/26 19:54:24 Debug: [easyhttp] [31] POST to https://metadata.roonlabs.net/1/works/translate?c=tidal-il returned after 246 ms, status code: 200
10/26 19:54:25 Debug: [easyhttp] [32] GET to https://discover.roonlabs.net/1/albums/121:0:MW0001940825/recommended/albums?profileId=11dbcf9e-6759-4eae-8462-35817ecad87f&count=30&c=tidal-il returned after 396 ms, status code: 200
10/26 19:54:25 Trace: [library] finished with 2 dirty performers 1 dirty places 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 2 changed objects
10/26 19:54:25 Trace: updated recents in 3ms
10/26 19:54:25 Trace: updated recents in 0ms
10/26 19:54:25 Trace: updated recents in 0ms
10/26 19:54:25 Trace: updated recents in 0ms
10/26 19:54:25 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 123737 bytes, 58 ops in 7727 ms)
10/26 19:54:26 Info: [library] saved recent ProfileId=11dbcf9e-6759-4eae-8462-35817ecad87f Time=10/26/2020 5:54:44 PM DataType=album Type=play MetadataId=44665 ContentId=11823 LibraryId=11823
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: [zone PC Output] Playing 3 Items
10/26 19:54:26 Trace: [zone PC Output] Selecting Source state=Paused
10/26 19:54:26 Info: [zone PC Output] Canceling Pending Sleep
10/26 19:54:26 Trace: [zone PC Output] Playing TransportItem
10/26 19:54:26 Info: [zone PC Output] Canceling Pending Sleep
10/26 19:54:26 Trace: [zone PC Output] Selecting Source state=Stopped
10/26 19:54:26 Trace: [PC Output] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [LOADING @ 0:00] Sonata for arpeggione & piano in A minor ("Arpeggione Sonata"), D. 821: Allegro moderato - Mstislav Rostropovich & Benjamin Britten / Franz Schubert
10/26 19:54:26 Trace: [musicpowerstate] music is playing, preventing idle sleep
10/26 19:54:26 Info: [PC Output] [zoneplayer] Playing: /share/CACHEDEV2_DATA/.qpkg/RoonServer/roonmnt/Music/iTunes Music/Benjamin Britten & Mstislav Rostropovich/Schubert_ Arpeggione Sonata/01 Sonata for Arpeggione and Piano in A Minor, D. 821_ I. Allegro moderato.m4a
10/26 19:54:26 Info: [PC Output] [zoneplayer] Queueing: /share/CACHEDEV2_DATA/.qpkg/RoonServer/roonmnt/Music/iTunes Music/Benjamin Britten & Mstislav Rostropovich/Schubert_ Arpeggione Sonata/02 Sonata for Arpeggione and Piano in A Minor, D. 821_ II. Adagio.m4a
10/26 19:54:26 Info: [PC Output] [zoneplayer]     Open Result (Playing):Result[Status=Success]
10/26 19:54:26 Info: [PC Output] [zoneplayer] Starting playback
10/26 19:54:26 Trace: [PC Output] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamParams[SampleRate=44100, BitsPerSample=24, Channels=2, Layout=Stereo FrameCount=35988480] => StreamParams[SampleRate=44100, BitsPerSample=24, Channels=2, Layout=Stereo FrameCount=35988480] streamid=651344217
10/26 19:54:26 Trace: [System Output] [raatclient] SENT [12]{"request":"setup","format":{"sample_type":"pcm","sample_rate":44100,"bits_per_sample":24,"channels":2,"sample_subtype":"none"}}
10/26 19:54:26 Info: [PC Output] [zoneplayer] Open result (Queueing): Result[Status=Success]
10/26 19:54:26 Info: [zone PC Output] OnPlayFeedback Playing
10/26 19:54:26 Trace: [PC Output] [LowQuality, 24/44 AAC => 24/44] [100% buf] [PLAYING @ 0:00] Sonata for arpeggione & piano in A minor ("Arpeggione Sonata"), D. 821: Allegro moderato - Mstislav Rostropovich & Benjamin Britten / Franz Schubert
10/26 19:54:26 Info: [library/albumdetails] Created album details screen for LibraryAlbum[11823, Benjamin Britten, Mstislav Rostropovich - Schubert: Arpeggione Sonata; Schumann: Fünf Stücke im Volkston; Debussy: Cello Sonata]
10/26 19:54:26 Info: [library/albumdetails] 79004d5730303031393430383235, 79004d5230303032373231343837, 79004d5230303034333836323436, 3e01561b39043419064e806cd2835d393c37, 3e013238c7dc1b86e44fb20926f95789b9dd, 79004d5230303034303034393739, a60034353539393533, a60034353432353930, a6003232373234343336, c80030303032383934383130323537, c80030303032383934373538323339, c80030303032383934363039373432, be0031303335353234, be003136353337343139
10/26 19:54:26 Trace: [library] finished with 1 dirty performers 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 1 changed objects
10/26 19:54:26 Trace: updated recents in 1ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 123737 bytes, 58 ops in 7729 ms)
10/26 19:54:26 Trace: [System Output] [raatclient] GOT [12] {"message":{"signal_path":[{"quality":"high","method":"wasapi_shared","type":"output"}]},"status":"OutputMessage"}10/26 19:54:26 Trace: [library] finished with 2 dirty performers 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 2 changed objects
10/26 19:54:26 Trace: [prebuffer] short read: 0 / 8820 fill=0
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: updated recents in 0ms
10/26 19:54:26 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 123737 bytes, 58 ops in 7732 ms)
10/26 19:54:26 Trace: [System Output] [raatclient] GOT [12] {"audio_port":0,"clock_port":52284,"audio_port_tcp":58870,"status":"Success"}
10/26 19:54:26 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Idle => Prepared
10/26 19:54:26 Trace: [PC Output] [zoneplayer/raat] synced to endpoint System Output clock: realtime=55999000 rtt=1000us offset=-423317001us delta=-423317001us
10/26 19:54:26 Trace: [System Output] [raatclient] SENT [13]{"request":"stream","stream_id":651344217,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
10/26 19:54:26 Trace: [System Output] [raatclient] GOT [13] {"status":"Buffering"}
10/26 19:54:26 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Prepared => Buffering
10/26 19:54:26 Trace: [PC Output] [zoneplayer/raat] [zoneplayer/raat] selected System Output as clock master
10/26 19:54:26 Trace: [prebuffer] short read: 0 / 8820 fill=0
10/26 19:54:26 Debug: [easyhttp] [33] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il returned after 178 ms, status code: 200
10/26 19:54:26 Info: [library/albumdetails] found 1 streaming service alternates
10/26 19:54:26 Debug: [easyhttp] [34] POST to https://metadata.roonlabs.net/1/works/translate?c=tidal-il returned after 236 ms, status code: 200
10/26 19:54:27 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Aac 44100/24/2 BitRate=257 Quality=LowQuality
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality SubType= Model=System Output
------------------------------------------------------------
10/26 19:54:30 Info: [stats] 3155mb Virtual, 784mb Physical, 230mb Managed, 0 Handles, 89 Threads
10/26 19:54:36 Trace: [PC Output] [LowQuality, 24/44 AAC => 24/44] [PLAYING @ 0:00] Sonata for arpeggione & piano in A minor ("Arpeggione Sonata"), D. 821: Allegro moderato - Mstislav Rostropovich & Benjamin Britten / Franz Schubert
10/26 19:54:45 Info: [stats] 3155mb Virtual, 784mb Physical, 230mb Managed, 0 Handles, 83 Threads

Playing Flac file:

10/26 19:53:30 Info: [stats] 3109mb Virtual, 775mb Physical, 243mb Managed, 0 Handles, 82 Threads
10/26 19:53:45 Info: [stats] 3109mb Virtual, 775mb Physical, 243mb Managed, 0 Handles, 80 Threads
10/26 19:53:51 Debug: [history] loading recent history for 11dbcf9e-6759-4eae-8462-35817ecad87f
10/26 19:53:51 Trace: [history] recent history loaded in 304ms
10/26 19:53:51 Trace: updated recents in 341ms
10/26 19:53:51 Trace: updated recents in 20ms
10/26 19:53:51 Trace: [dbperf] flush 0 bytes, 0 ops in 18 ms (cumulative 123737 bytes, 58 ops in 7701 ms)
10/26 19:53:51 Info: [library/albumdetails] Created album details screen for LibraryAlbum[871727, Ailish Tynan, Hebrides Ensemble, Judith Weir - Airs from another Planet]
10/26 19:53:51 Info: [library/albumdetails]
10/26 19:53:52 Trace: [library] finished with 2 dirty performers 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 2 changed objects
10/26 19:53:52 Trace: updated recents in 3ms
10/26 19:53:52 Trace: updated recents in 0ms
10/26 19:53:52 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 123737 bytes, 58 ops in 7703 ms)
10/26 19:53:52 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 123737 bytes, 58 ops in 7704 ms)
10/26 19:53:52 Debug: [easyhttp] [22] GET to https://tidal.roonlabs.net/1/featured/new/albums?offset=0&count=30&c=tidal-il returned after 1135 ms, status code: 304
10/26 19:53:52 Trace: updated recents in 5ms
10/26 19:53:52 Trace: updated recents in 0ms
10/26 19:53:52 Trace: [dbperf] flush 0 bytes, 0 ops in 18 ms (cumulative 123737 bytes, 58 ops in 7722 ms)
10/26 19:53:52 Debug: [easyhttp] [23] GET to https://tidal.roonlabs.net/1/featured/top/albums?offset=0&count=30&c=tidal-il returned after 1531 ms, status code: 304
10/26 19:53:52 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=IL&offset=0&limit=30 => Success
10/26 19:53:52 Debug: [easyhttp] [27] GET to https://tidal.roonlabs.net/1/featured/top/albums?offset=0&count=30&c=tidal-il returned after 422 ms, status code: 304
10/26 19:53:52 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=IL&offset=0&limit=30 => Success
10/26 19:53:52 Debug: [easyhttp] [26] GET to https://tidal.roonlabs.net/1/featured/new/albums?offset=0&count=30&c=tidal-il returned after 441 ms, status code: 304
10/26 19:53:52 Debug: [easyhttp] [25] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-il returned after 649 ms, status code: 200
10/26 19:53:52 Info: [library/albumdetails] found 1 streaming service alternates
10/26 19:53:52 Debug: [easyhttp] [29] POST to https://metadata.roonlabs.net/1/works/translate?c=tidal-il returned after 456 ms, status code: 200
10/26 19:53:54 Debug: [easyhttp] [28] POST to https://discover.roonlabs.net/1/profiles/11dbcf9e-6759-4eae-8462-35817ecad87f/albums/newv2?count=400&c=tidal-il returned after 1814 ms, status code: 200
10/26 19:53:54 Debug: [easyhttp] [24] POST to https://discover.roonlabs.net/1/profiles/11dbcf9e-6759-4eae-8462-35817ecad87f/albums/newv2?count=400&c=tidal-il returned after 3061 ms, status code: 200
10/26 19:53:54 Trace: [zone PC Output] PlayPause
10/26 19:53:54 Trace: [zone PC Output] Selecting Source state=Stopped
10/26 19:53:54 Trace: [PC Output] [Inactive] [LOADING @ 0:00] Nuits d’Afrique: I. Berceuse - Hebrides Ensemble / Ailish Tynan
10/26 19:53:54 Trace: [musicpowerstate] music is playing, preventing idle sleep
10/26 19:53:54 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 123737 bytes, 58 ops in 7723 ms)
10/26 19:53:54 Info: [PC Output] [zoneplayer] Playing: /share/CACHEDEV2_DATA/.qpkg/RoonServer/roonmnt/Music/Roon/Judith Weir/Airs from another Planet/01 Nuits d’Afrique_ I. Berceuse.flac
10/26 19:53:54 Info: [PC Output] [zoneplayer] Queueing: /share/CACHEDEV2_DATA/.qpkg/RoonServer/roonmnt/Music/Roon/Judith Weir/Airs from another Planet/02 Nuits d’Afrique_ II. Le tam-tam.flac
10/26 19:53:54 Info: [PC Output] [zoneplayer]     Open Result (Playing):Result[Status=Success]
10/26 19:53:54 Info: [PC Output] [zoneplayer] Starting playback
10/26 19:53:54 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/223 sec
10/26 19:53:54 Trace: [PC Output] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) streamid=651344216
10/26 19:53:54 Trace: [System Output] [raatclient] SENT [6]{"request":"setup","format":{"sample_type":"pcm","sample_rate":44100,"bits_per_sample":16,"channels":2,"sample_subtype":"none"}}
10/26 19:53:54 Info: [PC Output] [zoneplayer] Open result (Queueing): Result[Status=Success]
10/26 19:53:54 Info: [zone PC Output] OnPlayFeedback Playing
10/26 19:53:54 Trace: [PC Output] [Inactive] [PLAYING @ 0:00] Nuits d’Afrique: I. Berceuse - Hebrides Ensemble / Ailish Tynan
10/26 19:53:54 Debug: GET /image/skbaaaaa.256.jpg
10/26 19:53:54 Debug: GET /image/xhjaaaaa.256.jpg
10/26 19:53:54 Debug: GET /image/axeaaaaa.1024.jpg
10/26 19:53:54 Debug: GET /image/hcbaaaaa.256.jpg
10/26 19:53:54 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 123737 bytes, 58 ops in 7724 ms)
10/26 19:53:55 Trace: [System Output] [raatclient] GOT [6] {"message":{"signal_path":[{"quality":"high","method":"wasapi_shared","type":"output"}]},"status":"OutputMessage"}
10/26 19:53:55 Trace: [System Output] [raatclient] GOT [6] {"audio_port":0,"clock_port":52284,"audio_port_tcp":58870,"status":"Success"}
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Idle => Prepared
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] synced to endpoint System Output clock: realtime=68001000 rtt=1000us offset=-392156999us delta=-392156999us
10/26 19:53:55 Trace: [System Output] [raatclient] SENT [7]{"request":"stream","stream_id":651344216,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
10/26 19:53:55 Trace: [System Output] [raatclient] GOT [7] {"status":"Buffering"}
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Prepared => Buffering
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] [zoneplayer/raat] selected System Output as clock master
10/26 19:53:55 Debug: [raat/tcpaudiosource] connecting to 192.168.1.236:58870
10/26 19:53:55 Debug: [raat/tcpaudiosource] connected
10/26 19:53:55 Trace: [System Output] [raatclient] GOT [7] {"status":"Ready"}
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Buffering => Ready
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] wait for ready in 23ms
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] Adjusting playback start offset from 50ms to 270ms
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
10/26 19:53:55 Trace: [System Output] [raatclient] SENT [8]{"request":"start","min_offset":270000000,"stream_sample":0}
10/26 19:53:55 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Ready => Playing
10/26 19:53:55 Trace: [System Output] [raatclient] GOT [7] {"status":"Playing"}
10/26 19:53:55 Trace: [System Output] [raatclient] GOT [8] {"time":395239158,"status":"Success"}
10/26 19:53:56 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 44100/16/2 BitRate=579 Quality=Lossless
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality SubType= Model=System Output
------------------------------------------------------------
10/26 19:54:00 Trace: [PC Output] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 0:04/3:43] Nuits d’Afrique: I. Berceuse - Hebrides Ensemble / Ailish Tynan
10/26 19:54:00 Info: [stats] 3121mb Virtual, 775mb Physical, 213mb Managed, 0 Handles, 85 Threads
10/26 19:54:05 Trace: [PC Output] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 0:09/3:43] Nuits d’Afrique: I. Berceuse - Hebrides Ensemble / Ailish Tynan
10/26 19:54:07 Trace: [zone PC Output] PlayPause
10/26 19:54:07 Trace: [zone PC Output] Pause
10/26 19:54:07 Info: [zone PC Output] Canceling Pending Sleep
10/26 19:54:07 Trace: [PC Output] [zoneplayer/raat] Pausing at streamtime_ns 11977692145 now_ns 12372931303
10/26 19:54:07 Info: [zone PC Output] OnPlayFeedback Paused
10/26 19:54:07 Trace: [System Output] [raatclient] SENT [9]{"request":"stop"}
10/26 19:54:07 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Playing => Paused
10/26 19:54:07 Trace: [PC Output] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PAUSED @ 0:11/3:43] Nuits d’Afrique: I. Berceuse - Hebrides Ensemble / Ailish Tynan
10/26 19:54:07 Trace: [System Output] [raatclient] GOT [7] {"status":"Stopped"}
10/26 19:54:07 Trace: [System Output] [raatclient] GOT [9] {"status":"Success"}
10/26 19:54:12 Trace: [zone PC Output] [zone] no playback for 5s, suspending to release audio device
10/26 19:54:12 Trace: [zone PC Output] Suspend
10/26 19:54:12 Trace: [zone PC Output] Stop
10/26 19:54:12 Info: [zone PC Output] OnPlayFeedback Stopped
10/26 19:54:12 Info: [zone PC Output] Canceling Pending Sleep
10/26 19:54:12 Info: [audio/env] [zoneplayer] All streams were disposed
10/26 19:54:12 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
10/26 19:54:12 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Paused => Prepared
10/26 19:54:12 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
10/26 19:54:12 Trace: [System Output] [raatclient] SENT [10]{"request":"end_stream"}
10/26 19:54:12 Trace: [System Output] [raatclient] SENT [11]{"request":"teardown"}
10/26 19:54:12 Trace: [PC Output] [zoneplayer/raat] Endpoint System Output State Changed: Prepared => Idle
10/26 19:54:12 Debug: [raat/tcpaudiosource] disconnecting
10/26 19:54:12 Trace: [System Output] [raatclient] GOT [7] {"status":"Ended"}
10/26 19:54:12 Trace: [System Output] [raatclient] GOT [10] {"status":"Success"}
10/26 19:54:13 Trace: [System Output] [raatclient] GOT [6] {"status":"Teardown"}
10/26 19:54:13 Info: sleep 267ms after flush

@support, Again, Please help. For this kind of problem I don’t think the community can help.

nuwriy · October 26, 2020, 9:51pm

Hello @Haim_Ashkenazi, and thanks for your report! Do you have any issues playing other kinds of content (streamed or local) to system output? Also, can you send me a screenshot of Settings>Storage?

Haim_Ashkenazi · October 26, 2020, 10:36pm

Hi @nuwriy, Thanks for the response.

I only have two types of music files, Flac and M4a (plus Tidal high res streaming). As long as I don’t play any m4a file I can play all other formats (flac and tidal). Once I play m4a file I can’t play any other files on this client until I restart the Roon Core (not the client).

See attached storage screenshot:

Scott_Schnoor · October 28, 2020, 12:20am

Hello Haim, I have exactly the same problem and like you it took me a few days to isolate it to M4a (AAC) audio files. I’m running a QNAP 877 for the Roon Core with latest firmware TS-X77_20201024-4.5.1.1465. Have 3 end points using Roon client, 2 Windows 10 v 2004 and 1 iPhone 8 with iOS 14.1. thought it was the recent Roon software update but now I’m thinking its the QNAP firmware. Either way any guidance or help to resolve would be appreciated. For now I think I will have to remove all m4a audio files so I do not “corrupt” the pipeline I like to run a client 24x7 and it chokes when it hits a M4a song.

Haim_Ashkenazi · October 28, 2020, 6:16am

Hi @Scott_Schnoor,

Thanks for the report. I doubt it’s the Qnap firmware upgrade. IIRC the problem started for me before I installed the last update (I only noticed that there is an upgrade when I logged in into the Qnap administration to restart Roon when this behavior began). In any case the files are read without a problem - I can play them from a mount.

For now I hope @support is looking into it. @nuwriy, If you need any more information please let me know.

nuwriy · October 28, 2020, 1:54pm

Hello @Haim_Ashkenazi, do you have another machine you can try as the core to see if it produces the same result? Let me know how this goes!

Rory_Norman · October 28, 2020, 2:37pm

I am having this problem too. My QNAP is running 4.5.1.1465 which is the second update in the last ten days. Is this problem unique to one version of the os or is it happening to people on 4.4 or 4.3 also. Could the problem be as simple as a missing codec? Would that be a QNAP issue or are they part of the Roon system?

Haim_Ashkenazi · October 28, 2020, 3:11pm

Hi @nuwriy,

As I said in the first message, I have two clients, one on windows and one on Mac and both are having exactly the same issue.

ged_hickman1 · October 28, 2020, 3:57pm

Newry was asking if you could use a different machine as the core not an end point

Haim_Ashkenazi · October 28, 2020, 4:00pm

Oops, sorry, I still have my old database on my mac. I’ll try it later today and report.

Haim_Ashkenazi · October 28, 2020, 4:32pm

Hi @nuwriy,

When using my old database on my Mac it plays M4a files both locally and remotely. Later today I’ll try to install Linux VM and see how it works there. @Rory_Norman has the same problem on another Qnap so maybe it will help us understand whether it’s a Linux or a Qnap issue.

Haim_Ashkenazi · October 28, 2020, 7:19pm

@nuwriy, When trying to install Roon on Linux it failed because it didn’t have ffmpeg installed on the system. I checked and Qnap also provides ffmpeg in /usr/bin. Maybe it’s faulty in some way? Can you give example of valid usage that Roon uses that I can try running on M4a file compared to Flac file?

Thanks

Haim_Ashkenazi · October 28, 2020, 7:52pm

@nuwriy, @Rory_Norman, I installed Roon on Linux VM (ubuntu 20.04) and copy two types of files (only few files, not the entire collection) and it seems to play all files.

Maybe testing ffmpeg on Qnap is a good way to continue debugging?

Haim_Ashkenazi · October 29, 2020, 4:05pm

I tried this using the ffmpeg in qnap:

 # ffmpeg -i 1-02\ Rambi.m4a -c:a flac /tmp/audio.flac
[...]
Decoder (codec aac) not found for input stream #0:0

On other type of files (e.g. mp3) it does work.

@support, @nuwriy is this something you’re using? If I’ll try to contact Qnap with this issue, will it also fix this?

Update: I opened a ticket with Qnap on this.

crieke · October 29, 2020, 9:12pm

I am also currently writing with QNAP about this issue.

Haim_Ashkenazi · October 30, 2020, 11:46am

@Rory_Norman and whoever else is having this issue. @crieke tested with custom ffmpeg and indeed this was the problem. I guess a solution is on the way .

Rory_Norman · October 30, 2020, 12:20pm

Thanks. Looks like our problem is part of a broader one that has been plaguing people for a month or so. Maybe a lot of people will be cheering when we get a solution.