ROON consume all physical memory within 3 hours after an unknown issue

Support
1

Roon Core Machine

  • QNAP: QGD-1600P
  • CPU: Intel(R) Celeron(R) J4115 CPU , tot 2500 MHz (4 cores, 4 threads)
  • BIOS version: Q01YAR10
  • Memory 32 GB
  • QNAP Firmware: 5.0.0.1850 Build 20211111

Networking Gear & Setup Details

  • QNAP: QGD-1600P ( 16 x 1 GBit ports)
  • 3 Wifi TP-Link Amada EAP615-Wall Accespoints

Connected Audio Devices

  • 4 x Harman Kardon Citation One MKII (Stereo pairing/Google Chromcast Group)
  • Android TV / Chromecast
  • Android phone with Roon App
  • Android tablet with Roon App

Number of Tracks in Library

  • 21406 tracks / 1325 Albums
  • Qobuz subscription

Description of Issue

After starting an playlist or Roon Radio with a combination of Qobuz and internal playlist at some Roon began to consume within 3 hours al available memory of the NAS device (32 Gbyte). The Roon will reset and everything seems to be normal again.

I have attached an overview with the memory consumed over the time as image and log file. It seems that at time 17:17 something happened when after the physical memory consumption wille grow.

Memory consumption
Memory consumption1913Ă—568 29.8 KB

The logfile of RoonOnNas.txt

12.11.21-12:44:45 --- Starting RoonServer ...
12.11.21-12:44:45 --- ROON_DATABASE_DIR: /share/Roon/RoonOnNAS - [available]
12.11.21-12:44:45 --- ROON_DATABASE_DIR_FS: ext4
12.11.21-12:44:45 --- ROON_ID_DIR: /share/CACHEDEV1_DATA/.qpkg/RoonServer/id - [available]
12.11.21-12:44:45 --- Free Inodes: 62.1M
12.11.21-12:44:45 --- ROON_DIR: /share/CACHEDEV1_DATA/.qpkg/RoonServer
12.11.21-12:44:45 --- Model: QGD-1600P
12.11.21-12:44:45 --- QNAP Serial: Q19CI14945
12.11.21-12:44:45 --- Architecture: x86_64
12.11.21-12:44:45 --- Total Memory: 32714560
12.11.21-12:44:45 --- Available Memory: 28949548
12.11.21-12:44:45 --- QTS Version: 5.0.0 - Build: 20211111
12.11.21-12:44:45 --- PKG Version: 2021-10-03
12.11.21-12:44:45 --- Installed QTS Apps: NotificationCenter,ResourceMonitor,Qboost,SSDLaboratory,helpdesk,netmgr,QuLog,QsyncServer,QuNetSwitch,MalwareRemover,HybridBackup,container-station,PlexMediaServer,CloudLink,QVPN,RoonServer,QDMS,LicenseCenter,QcloudSSLCertificate,
12.11.21-12:44:45 --- Hostname: meterkast
12.11.21-12:44:45 --- MTU: 1500 1500 1500

############### Used FFMPEG Version ##############
ffmpeg version 4.4.1-static https://johnvansickle.com/ffmpeg/ Copyright (c) 2000-2021 the FFmpeg developers built with gcc 8 (Debian 8.3.0-6) configuration: --enable-gpl --enable-version3 --enable-static --disable-debug --disable-ffplay --disable-indev=sndio --disable-outdev=sndio --cc=gcc --enable-fontconfig --enable-frei0r --enable-gnutls --enable-gmp --enable-libgme --enable-gray --enable-libaom --enable-libfribidi --enable-libass --enable-libvmaf --enable-libfreetype --enable-libmp3lame --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenjpeg --enable-librubberband --enable-libsoxr --enable-libspeex --enable-libsrt --enable-libvorbis --enable-libopus --enable-libtheora --enable-libvidstab --enable-libvo-amrwbenc --enable-libvpx --enable-libwebp --enable-libx264 --enable-libx265 --enable-libxml2 --enable-libdav1d --enable-libxvid --enable-libzvbi --enable-libzimg libavutil 56. 70.100 / 56. 70.100 libavcodec 58.134.100 / 58.134.100 libavformat 58. 76.100 / 58. 76.100 libavdevice 58. 13.100 / 58. 13.100 libavfilter 7.110.100 / 7.110.100 libswscale 5. 9.100 / 5. 9.100 libswresample 3. 9.100 / 3. 9.100 libpostproc 55. 9.100 / 55. 9.100
##################################################

12.11.21-12:44:45 --- RoonServer PID: 5737


########## Installed RoonServer Version ##########
100800850
1.8 (build 850) stable
stable
##################################################


12.11.21-12:44:46  --- 00:00:00.001 Warn:  get lock file path: /tmp/.rnsgem0-
12.11.21-12:44:46  --- Initializing
12.11.21-12:44:46  --- Started
12.11.21-12:44:51  --- Running
14.11.21-17:32:57  --- System.Net.Sockets.SocketException (104): Connection reset by peer
14.11.21-17:32:57  --- at System.Net.Sockets.Socket.EndReceive(IAsyncResult asyncResult)
14.11.21-17:32:57  --- at Sooloos.RnetJsonClient.<>c__DisplayClass65_0.<_BeginRead>b__0(IAsyncResult ar)
14.11.21-20:30:14  --- Not responding
14.11.21-20:30:55  --- Error
14.11.21-20:30:57  --- Initializing
14.11.21-20:30:57  --- Started
14.11.21-20:30:59  --- Running

Part of the log of the RoonServer_log.txt

11/14 17:17:02 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 359:28] Adele - Easy On Me
11/14 17:17:02 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:07 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 359:34] Adele - Easy On Me
11/14 17:17:07 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:10 Info: [brokerserver] Client connected: 192.168.1.136:38672
11/14 17:17:10 Trace: [raat] [sood] Refreshing device list
11/14 17:17:10 Trace: [raatserver] [sood] Refreshing device list
11/14 17:17:10 Info: [brokerserver] Client disconnected: 192.168.1.136:38672
11/14 17:17:10 Trace: [raat] RAATServer discovered: RaatServer VOG-L29 @ 192.168.1.136:9200
11/14 17:17:10 Info: [raatserver] GOT SERVER f03da3a4-e8ba-0dbc-5b7e-a2a38a94604d::f03da3a4e8ba0dbc5b7ea2a38a94604d @ 192.168.1.136:9200 VOG-L29 PROTOVER=1 RAATVER=1.1.38 
11/14 17:17:10 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
11/14 17:17:10 Trace: [push] retrying connection in 29534ms
11/14 17:17:10 Trace: [raatserver] [RaatServer VOG-L29 @ 192.168.1.136:9200] connecting (attempt 1)
11/14 17:17:10 Trace: [raatserver] [RaatServer VOG-L29 @ 192.168.1.136:9200] connected
11/14 17:17:10 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
11/14 17:17:10 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"devices": [{"is_system_output": true, "type": "android", "device_id": "default", "auto_name": "VOG-L29", "name": "Default Output", "auto_enable": true, "discovery_data": {"tcp_port": 33235, "vendor": "N/A", "unique_id": "37d306d0-6a3c-f4b0-8ad1-c86965c15d4b", "raat_version": "1.1.38", "version": "N/A", "protocol_version": "3", "model": "N/A"}, "config": {"unique_id": "37d306d0-6a3c-f4b0-8ad1-c86965c15d4b", "output": {"type": "android", "device": "default", "name": "Default Output"}, "volume": {"type": "android", "device": "default"}, "external_config": {"is_private": true}}}], "status": "Success"}
11/14 17:17:10 Info: [raatserver] GOT DEVICE f03da3a4e8ba0dbc5b7ea2a38a94604d::default Type=android Name=Default Output 
11/14 17:17:10 Trace: [rnet/RnetJsonClient] SENT {"request":"enable_device","device_id":"default","subscription_id":"10"}
11/14 17:17:10 Info: [raatserver] [Default Output] connecting (attempt 1)
11/14 17:17:10 Trace: [Default Output] [raatclient] Initializing RAAT session
11/14 17:17:10 Trace: [Default Output] [raatclient] Loading script raat.dat:base.lua as module base [1088 chars]
11/14 17:17:10 Trace: [Default Output] [raatclient] Loading script raat.dat:dkjson.lua as module dkjson [22416 chars]
11/14 17:17:10 Trace: [Default Output] [raatclient] Loading script raat.dat:protocol.lua as module protocol [2045 chars]
11/14 17:17:10 Trace: [Default Output] [raatclient] Loading script raat.dat:roon_tcp.lua [43548 chars]
11/14 17:17:10 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success"}
11/14 17:17:11 Info: [stats] 23761mb Virtual, 1193mb Physical, 439mb Managed, 395 Handles, 125 Threads
11/14 17:17:11 Debug: [easyhttp] [1998] POST to https://discovery.roonlabs.net/1/query returned after 403 ms, status code: 200
11/14 17:17:11 Trace: [Default Output] [raatclient] RAAT Session initialized in 590ms
11/14 17:17:11 Trace: [Default Output] [raatclient] SENT [2]{"request":"info"}
11/14 17:17:11 Trace: [Default Output] [raatclient] SENT [3]{"request":"set_client_type","client_type":"Roon"}
11/14 17:17:11 Trace: [Default Output] [raatclient] GOT [2] {"source_selection":{"is_supported":false,"info":[]},"info":{"vendor":"N/A","model":"N/A","protocol_version":"3","raat_version":"1.1.38","unique_id":"37d306d0-6a3c-f4b0-8ad1-c86965c15d4b","version":"N/A"},"flags":{"has_write_chmap":true},"volume":{"is_supported":true,"info":{}},"status":"Success","transport":{"info":{"config":{}},"is_update_artwork_supported":true,"is_supported":true,"is_update_status_supported":true},"platform":{"os":"android","uname":{"nodename":"localhost","machine":"aarch64","sysname":"Linux","release":"4.14.116","version":"#1 SMP PREEMPT Fri Jul 9 17:36:17 CST 2021"}},"output":{"supported_formats":"48000/16/1|2,48000/24/1|2","info":{"refresh_supported_formats_before_playback":true}}}
11/14 17:17:11 Trace: [Default Output] [raatclient] GOT [3] {"status":"Success"}
11/14 17:17:11 Trace: [raatserver] [Default Output] connected
11/14 17:17:11 Info: [transport] creating endpoint for raat device 37d306d0-6a3c-f4b0-8ad1-c86965c15d4b
11/14 17:17:11 Trace: [Default Output] [raatclient] SENT [4]{"request":"subscribe_transport"}
11/14 17:17:11 Trace: [Default Output] [raatclient] SENT [5]{"request":"subscribe_controls","controller_id":"d7066d11-78d2-4f1f-8e6b-f68f02a46245"}
11/14 17:17:11 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"bs2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
11/14 17:17:11 Trace: [transport/audeze] picking preset lowlatency 
11/14 17:17:11 Trace: [transport/audeze] picked null preset!
11/14 17:17:11 Trace: [Default Output] [raatclient] GOT [5] {"status":"Success","action":"Changed","controls":{"volume":{"min":0.0,"mute":false,"step":1.0,"value":0,"type":"number","info":{},"max":15.0}}}
11/14 17:17:12 Trace: [zone VOG-L29] Loading
11/14 17:17:12 Trace: [zone VOG-L29] Suspend
11/14 17:17:12 Info: [zone VOG-L29] Canceling Pending Sleep
11/14 17:17:12 Trace: [VOG-L29] [zoneplayer/raat] Endpoint Default Output Initial State: Idle
11/14 17:17:12 Info: [transport] created zone VOG-L29
11/14 17:17:12 Info: [transport]     with endpoint Default Output (23:1:37d306d0-6a3c-f4b0-8ad1-c86965c15d4b) [IsAvailable=True]
11/14 17:17:12 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 359:37] Adele - Easy On Me
11/14 17:17:12 Trace: [VOG-L29] [HighQuality, 16/44 QOBUZ FLAC => 24/44] [100% buf] [STOPPED @ 0:00] 
11/14 17:17:12 Trace: [roonapi] [apiclient 192.168.1.14:41014] CONTINUE Changed {"zones_added":[{"zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","display_name":"VOG-L29","outputs":[{"output_id":"1701d006d3373c6ab0f48ad1c86965c15d4b","zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","can_group_with_output_ids":["1701d006d3373c6ab0f48ad1c86965c15d4b"],"display_name":"VOG-L29","source_controls":[{"control_key":"1","display_name":"Default Output","supports_standby":false,"status":"selected"}]}],"state":"stopped","is_next_allowed":false,"is_previous_allowed":true,"is_pause_allowed":false,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":0,"queue_time_remaining":0,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true}}]}
11/14 17:17:12 Trace: [roonapi] [apiclient 192.168.1.14:41014] CONTINUE Changed {"outputs_added":[{"output_id":"1701d006d3373c6ab0f48ad1c86965c15d4b","zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","can_group_with_output_ids":["1701d006d3373c6ab0f48ad1c86965c15d4b"],"display_name":"VOG-L29","source_controls":[{"control_key":"1","display_name":"Default Output","supports_standby":false,"status":"selected"}]}]}
11/14 17:17:14 Info: [swim] Loaded Swim : TransportDb.SwimMediaSpec={ TrackIds=6426674 LimitToSeed=False LimitToLibrary=False PreplayTrackFromSeed=False IncludeHidden=False }
11/14 17:17:14 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
11/14 17:17:14 Trace: [roonapi] [apiclient 192.168.1.14:41014] CONTINUE Changed {"zones_changed":[{"zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","display_name":"VOG-L29","outputs":[{"output_id":"1701d006d3373c6ab0f48ad1c86965c15d4b","zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","can_group_with_output_ids":[],"display_name":"VOG-L29","volume":{"type":"number","min":0,"max":15,"value":0,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":15,"soft_limit":15},"source_controls":[{"control_key":"1","display_name":"Default Output","supports_standby":false,"status":"indeterminate"}]}],"state":"stopped","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":169,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":169,"one_line":{"line1":"Friday - Riton / Nightcrawlers / Mufasa & Hypeman"},"two_line":{"line1":"Friday","line2":"Riton / Nightcrawlers / Mufasa & Hypeman"},"three_line":{"line1":"Friday","line2":"Riton / Nightcrawlers / Mufasa & Hypeman","line3":"Friday "},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b385d17f5dc9f0b098602fe83cc259d4a9dffeed659015b0ce7789520ee7e6199c79a11b05707fa2c9fb240c07690d260bb243f3cb97e28a6e50e45e73de1653a112","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbac35c1879887a8ca0279ba3b9c7059ceb4092133c5b73f06778d001dcab7fdaa17271ca9738635a966456c09ffb8065b"]}}],"zones_seek_changed":[{"zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","queue_time_remaining":169,"seek_position":null}]}
11/14 17:17:14 Debug: [easyhttp] [2000] GET to https://metadata.roonlabs.net/1/tracks/202:0:109290897/lyrics returned after 404 ms, status code: 304
11/14 17:17:15 Trace: [Default Output] [raatclient] SENT [8]{"request":"update_artwork","mimetype":"image/jpeg"}
11/14 17:17:15 Debug: [easyhttp] [1999] POST to https://swim.roonlabs.net/1/session/begin returned after 1278 ms, status code: 200
11/14 17:17:15 Trace: [mlradio] [20] Got Radio Description {
    "tracks": [
        {
            "title": "Love Tonight [Vintage Culture & Kiko Franco Remix]",
            "main_performers": [
                "Shouse"
            ],
            "album": {
                "title": "Love Tonight",
                "performed_by": "Shouse"
            }
        }
    ]
}
11/14 17:17:15 Trace: [Default Output] [raatclient] GOT [8] {"status":"Success"}
11/14 17:17:15 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:15 Debug: [easyhttp] [2001] POST to https://metadata.roonlabs.net/1/tracks/translate?c=qobuz-nl returned after 401 ms, status code: 200
11/14 17:17:16 Trace: [mlradio] [20] skipping 192:0:31488946 [not available]
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:292490289 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] skipping 192:0:145671802 [not available]
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:301482781 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:298171684 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:296029186 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:304851249 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:287555877 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:112764626 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:285352234 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:296518130 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:157216947 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:290265444 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:152387138 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:293576120 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:270263764 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] skipping 192:0:153578938 [not available]
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:297957294 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:285602461 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:291162575 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:294940298 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:284801442 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:192376812 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:154225608 from Qobuz
11/14 17:17:16 Trace: [mlradio] [20] accepting 192:0:153927413 from Qobuz
11/14 17:17:16 Trace: [dbperf] flush 0 bytes, 0 ops in 6 ms (cumulative 290558 bytes, 449 ops in 13976 ms)
11/14 17:17:16 Trace: [mlradio] [20] [music/mlradio] GetNextItem(1 / 22)
11/14 17:17:16 Trace: [mlradio] [20] [music/mlradio] returning 1 items for request of 1 items
11/14 17:17:16 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
11/14 17:17:16 Trace: [zone VOG-L29] Loaded Queue=7658 Tracks Swim=Active AutoSwim=True Loop=Disabled Shuffle=False
11/14 17:17:16 Trace: [VOG-L29] [HighQuality, 16/44 QOBUZ FLAC => 24/44] [100% buf] [PAUSED @ 1:27/2:49] Friday - Riton / Nightcrawlers / Mufasa & Hypeman
11/14 17:17:16 Trace: [roonapi] [apiclient 192.168.1.14:41014] CONTINUE Changed {"zones_changed":[{"zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","display_name":"VOG-L29","outputs":[{"output_id":"1701d006d3373c6ab0f48ad1c86965c15d4b","zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","can_group_with_output_ids":[],"display_name":"VOG-L29","volume":{"type":"number","min":0,"max":15,"value":0,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":15,"soft_limit":15},"source_controls":[{"control_key":"1","display_name":"Default 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":1,"queue_time_remaining":82,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":87,"length":169,"one_line":{"line1":"Friday - Riton / Nightcrawlers / Mufasa & Hypeman"},"two_line":{"line1":"Friday","line2":"Riton / Nightcrawlers / Mufasa & Hypeman"},"three_line":{"line1":"Friday","line2":"Riton / Nightcrawlers / Mufasa & Hypeman","line3":"Friday "},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b385d17f5dc9f0b098602fe83cc259d4a9dffeed659015b0ce7789520ee7e6199c79a11b05707fa2c9fb240c07690d260bb243f3cb97e28a6e50e45e73de1653a112","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbac35c1879887a8ca0279ba3b9c7059ceb4092133c5b73f06778d001dcab7fdaa17271ca9738635a966456c09ffb8065b"]}}],"zones_seek_changed":[{"zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","queue_time_remaining":82,"seek_position":87}]}
11/14 17:17:17 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 359:44] Adele - Easy On Me
11/14 17:17:21 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
11/14 17:17:21 Trace: [push] connecting to 35.237.106.222:9204
11/14 17:17:21 Trace: [push] connected
11/14 17:17:22 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:23 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 359:50] Adele - Easy On Me
11/14 17:17:26 Info: [stats] 23781mb Virtual, 1486mb Physical, 719mb Managed, 399 Handles, 127 Threads
11/14 17:17:27 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:28 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 359:55] Adele - Easy On Me
11/14 17:17:32 Trace: [library] endmutation in 104ms
11/14 17:17:33 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 360:00] Adele - Easy On Me
11/14 17:17:37 Info: [brokerserver] Client connected: 192.168.1.136:38748
11/14 17:17:37 Trace: [raat] [sood] Refreshing device list
11/14 17:17:37 Trace: [raatserver] [sood] Refreshing device list
11/14 17:17:37 Info: [brokerserver] Client disconnected: 192.168.1.136:38748
11/14 17:17:37 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:38 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 360:04] Adele - Easy On Me
11/14 17:17:41 Info: [stats] 23853mb Virtual, 1847mb Physical, 796mb Managed, 400 Handles, 136 Threads
11/14 17:17:41 Debug: [easyhttp] [2002] POST to https://discovery.roonlabs.net/1/query returned after 392 ms, status code: 200
11/14 17:17:42 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:44 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 360:11] Adele - Easy On Me
11/14 17:17:47 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:49 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 360:15] Adele - Easy On Me
11/14 17:17:52 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/14 17:17:54 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 360:21] Adele - Easy On Me
11/14 17:17:56 Info: [stats] 23853mb Virtual, 1987mb Physical, 882mb Managed, 399 Handles, 136 Threads
11/14 17:18:00 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [PLAYING @ 360:27] Adele - Easy On Me
2

Hey Bart, sorry for the trouble here. Is this something that only happened once, or are you able to reproduce it consistently? If so, could you provide the steps to reproduce the issue?

4

It happened more than once. I have attached the memory average usage over a period of three days, where you see six spikes. At this moment I’m investigating the log files at the moment when the memory usages grows for any pattern, that can lead me to a clue.

Schermafdruk van 2021-11-22 17-00-17
Schermafdruk van 2021-11-22 17-00-171920Ă—516 45.7 KB

I shall try to upload more log information at the moment the memory usage starts to grow.

5

Thanks Bart, that would be really helpful! When those spikes “reset” is that because you’re restarting Roon, or does the memory return to normal on its own?

6

No I think Roon watchdog will reset the RoonServer, because the end of the spike is the same when the watchdog notice some error. See the RoonOnNAS log file:

########## Installed RoonServer Version ##########
100800850
1.8 (build 850) stable
stable
##################################################


20.11.21-10:56:01  --- e[0;37;1me[0;0m00:00:00.001 e[0;31mWarn:  get lock file path: /tmp/.rnsgem0-e[0;0m
20.11.21-10:56:01  --- Initializing
20.11.21-10:56:01  --- Started
20.11.21-10:56:06  --- Running
20.11.21-16:22:21  --- Not responding
20.11.21-16:23:01  --- Error
20.11.21-16:23:02  --- Not responding
20.11.21-16:23:03  --- Initializing
20.11.21-16:23:03  --- Started
20.11.21-16:23:07  --- Running
21.11.21-00:01:45  --- System.Net.Sockets.SocketException (104): Connection reset by peer
21.11.21-00:01:45  --- at System.Net.Sockets.Socket.EndReceive(IAsyncResult asyncResult)
21.11.21-00:01:45  --- at Sooloos.RnetJsonClient.<>c__DisplayClass65_0.<_BeginRead>b__0(IAsyncResult ar)
21.11.21-00:56:40  --- Not responding
21.11.21-00:57:20  --- Error
21.11.21-00:57:20  --- Not responding
21.11.21-00:57:22  --- Initializing
21.11.21-00:57:22  --- Started
21.11.21-00:57:25  --- Running
21.11.21-13:12:07  --- Not responding
21.11.21-13:12:14  --- Running
21.11.21-13:20:31  --- Not responding
21.11.21-13:21:08  --- Error
21.11.21-13:21:10  --- Initializing
21.11.21-13:21:10  --- Started
21.11.21-13:21:12  --- Not responding
21.11.21-13:21:17  --- Running
21.11.21-14:16:51  --- System.Net.Sockets.SocketException (104): Connection reset by peer
21.11.21-14:16:51  --- at System.Net.Sockets.Socket.EndReceive(IAsyncResult asyncResult)
21.11.21-14:16:51  --- at Sooloos.RnetJsonClient.<>c__DisplayClass65_0.<_BeginRead>b__0(IAsyncResult ar)
7

Hello Kevin,

Thanks for the quick respons. It seems that the memory snooping starting after starting/accessing a specific playlist. The playlist is a combination of local and Qobuz tracks. At this moment I’m not 100% percent sure if this is the real trigger.

8

Maybe there is som relation with the Android mobile Roon app. It seems that all memory consumption starting of happening when I use the mobile app or the mobile app became active again.

This morning the memory consumption starting after I awake my mobile phone, while the app was active but not playing music. In the RoonServer Log file I see a stable memory consumption till the mobile App (VOG-L29) do something. After that, the memory grows.

11/23 07:38:29 Info: [stats] 23532mb Virtual, 1371mb Physical, 447mb Managed, 392 Handles, 90 Threads
11/23 07:38:29 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:38:33 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 19:33] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:38:38 Info: [brokerserver] Client connected: 192.168.1.136:42758
11/23 07:38:38 Trace: [raat] [sood] Refreshing device list
11/23 07:38:38 Trace: [raatserver] [sood] Refreshing device list
11/23 07:38:38 Trace: [raat] RAATServer discovered: RaatServer VOG-L29 @ 192.168.1.136:9200
11/23 07:38:38 Info: [raatserver] GOT SERVER f03da3a4-e8ba-0dbc-5b7e-a2a38a94604d::f03da3a4e8ba0dbc5b7ea2a38a94604d @ 192.168.1.136:9200 VOG-L29 PROTOVER=1 RAATVER=1.1.38 
11/23 07:38:38 Trace: [push] short read from connector (1)
11/23 07:38:38 Trace: [push] retrying connection in 97807ms
11/23 07:38:38 Trace: [raatserver] [RaatServer VOG-L29 @ 192.168.1.136:9200] connecting (attempt 1)
11/23 07:38:38 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=d7066d11-78d2-4f1f-8e6b-f68f02a46245 BrokerName='meterkast' }
11/23 07:38:38 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800850 DisplayValue='1.8 (build 850) stable' Branch='stable' } }
11/23 07:38:38 Trace: [raatserver] [RaatServer VOG-L29 @ 192.168.1.136:9200] connected
11/23 07:38:38 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
11/23 07:38:38 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "type": "android", "discovery_data": {"model": "N/A", "tcp_port": 33047, "unique_id": "37d306d0-6a3c-f4b0-8ad1-c86965c15d4b", "raat_version": "1.1.38", "vendor": "N/A", "version": "N/A", "protocol_version": "3"}, "auto_enable": true, "name": "Default Output", "config": {"volume": {"type": "android", "device": "default"}, "unique_id": "37d306d0-6a3c-f4b0-8ad1-c86965c15d4b", "external_config": {"is_private": true}, "output": {"type": "android", "name": "Default Output", "device": "default"}}, "is_system_output": true, "auto_name": "VOG-L29"}]}
11/23 07:38:38 Info: [raatserver] GOT DEVICE f03da3a4e8ba0dbc5b7ea2a38a94604d::default Type=android Name=Default Output 
11/23 07:38:38 Trace: [rnet/RnetJsonClient] SENT {"request":"enable_device","device_id":"default","subscription_id":"14"}
11/23 07:38:38 Info: [raatserver] [Default Output] connecting (attempt 1)
11/23 07:38:38 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success"}
11/23 07:38:38 Trace: [Default Output] [raatclient] Initializing RAAT session
11/23 07:38:38 Trace: [Default Output] [raatclient] Loading script raat.dat:base.lua as module base [1088 chars]
11/23 07:38:38 Trace: [Default Output] [raatclient] Loading script raat.dat:dkjson.lua as module dkjson [22416 chars]
11/23 07:38:38 Trace: [Default Output] [raatclient] Loading script raat.dat:protocol.lua as module protocol [2045 chars]
11/23 07:38:38 Trace: [Default Output] [raatclient] Loading script raat.dat:roon_tcp.lua [43548 chars]
11/23 07:38:38 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 19:37] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:38:39 Debug: [easyhttp] [1579] POST to https://discovery.roonlabs.net/1/query returned after 443 ms, status code: 200
11/23 07:38:39 Debug: [easyhttp] [1580] GET to https://messaging.roonlabs.net/1/api/messages/6c315745-58c4-47c6-b73c-24b8acdd8f3c returned after 455 ms, status code: 200
11/23 07:38:39 Trace: [Default Output] [raatclient] RAAT Session initialized in 758ms
11/23 07:38:39 Trace: [Default Output] [raatclient] SENT [2]{"request":"info"}
11/23 07:38:39 Trace: [Default Output] [raatclient] SENT [3]{"request":"set_client_type","client_type":"Roon"}
11/23 07:38:39 Trace: [Default Output] [raatclient] GOT [2] {"volume":{"info":{},"is_supported":true},"source_selection":{"info":[],"is_supported":false},"flags":{"has_write_chmap":true},"output":{"info":{"refresh_supported_formats_before_playback":true},"supported_formats":"48000/16/1|2,48000/24/1|2"},"info":{"protocol_version":"3","version":"N/A","unique_id":"37d306d0-6a3c-f4b0-8ad1-c86965c15d4b","raat_version":"1.1.38","vendor":"N/A","model":"N/A"},"status":"Success","platform":{"os":"android","uname":{"version":"#1 SMP PREEMPT Thu Oct 14 10:34:42 CST 2021","sysname":"Linux","nodename":"localhost","machine":"aarch64","release":"4.14.116"}},"transport":{"is_update_status_supported":true,"info":{"config":{}},"is_update_artwork_supported":true,"is_supported":true}}
11/23 07:38:39 Trace: [Default Output] [raatclient] GOT [3] {"status":"Success"}
11/23 07:38:39 Trace: [raatserver] [Default Output] connected
11/23 07:38:39 Info: [transport] creating endpoint for raat device 37d306d0-6a3c-f4b0-8ad1-c86965c15d4b
11/23 07:38:39 Trace: [Default Output] [raatclient] SENT [4]{"request":"subscribe_transport"}
11/23 07:38:39 Trace: [Default Output] [raatclient] SENT [5]{"request":"subscribe_controls","controller_id":"d7066d11-78d2-4f1f-8e6b-f68f02a46245"}
11/23 07:38:39 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"bs2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
11/23 07:38:39 Trace: [transport/audeze] picking preset lowlatency 
11/23 07:38:39 Trace: [transport/audeze] picked null preset!
11/23 07:38:39 Trace: [Default Output] [raatclient] GOT [5] {"status":"Success","controls":{"volume":{"step":1.0,"type":"number","min":0.0,"max":15.0,"info":{},"value":8,"mute":false}},"action":"Changed"}
11/23 07:38:39 Trace: [zone VOG-L29] Loading
11/23 07:38:39 Trace: [zone VOG-L29] Suspend
11/23 07:38:39 Info: [zone VOG-L29] Canceling Pending Sleep
11/23 07:38:39 Trace: [VOG-L29] [zoneplayer/raat] Endpoint Default Output Initial State: Idle
11/23 07:38:39 Info: [transport] created zone VOG-L29
11/23 07:38:39 Info: [transport]     with endpoint Default Output (23:1:37d306d0-6a3c-f4b0-8ad1-c86965c15d4b) [IsAvailable=True]
11/23 07:38:39 Trace: [VOG-L29] [HighQuality, 16/44 FLAC => 24/48] [100% buf] [STOPPED @ 0:00] 
11/23 07:38:39 Trace: [roonapi] [apiclient 192.168.1.14:56820] CONTINUE Changed {"zones_added":[{"zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","display_name":"VOG-L29","outputs":[{"output_id":"1701d006d3373c6ab0f48ad1c86965c15d4b","zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","can_group_with_output_ids":[],"display_name":"VOG-L29","source_controls":[{"control_key":"1","display_name":"Default Output","supports_standby":false,"status":"selected"}]}],"state":"stopped","is_next_allowed":false,"is_previous_allowed":true,"is_pause_allowed":false,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":0,"queue_time_remaining":0,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true}}]}
11/23 07:38:39 Trace: [roonapi] [apiclient 192.168.1.14:56820] CONTINUE Changed {"outputs_added":[{"output_id":"1701d006d3373c6ab0f48ad1c86965c15d4b","zone_id":"1601d006d3373c6ab0f48ad1c86965c15d4b","can_group_with_output_ids":[],"display_name":"VOG-L29","source_controls":[{"control_key":"1","display_name":"Default Output","supports_standby":false,"status":"selected"}]}]}
11/23 07:38:39 Trace: [zone VOG-L29] Loaded Queue=1721 Tracks Swim=Inactive AutoSwim=True Loop=Disabled Shuffle=False
11/23 07:38:39 Trace: [VOG-L29] [HighQuality, 16/44 FLAC => 24/48] [100% buf] [PAUSED @ 0:33/2:46] Under My Thumb - Anakelly
11/23 07:38:40 Trace: [Default Output] [raatclient] SENT [8]{"request":"update_artwork","mimetype":"image/jpeg"}
11/23 07:38:40 Info: [brokerserver] Client connected: 192.168.1.136:42702
11/23 07:38:40 Trace: [raat] [sood] Refreshing device list
11/23 07:38:40 Trace: [raatserver] [sood] Refreshing device list
11/23 07:38:40 Info: [brokerserver] Client disconnected: 192.168.1.136:42702
11/23 07:38:40 Trace: [Default Output] [raatclient] GOT [8] {"status":"Success"}
11/23 07:38:40 Info: [brokerserver] Client connected: 192.168.1.136:42724
11/23 07:38:40 Trace: [raat] [sood] Refreshing device list
11/23 07:38:40 Trace: [raatserver] [sood] Refreshing device list
11/23 07:38:40 Info: [brokerserver] Client disconnected: 192.168.1.136:42724
11/23 07:38:40 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:38:43 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 19:40] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:38:44 Info: [stats] 23862mb Virtual, 1743mb Physical, 535mb Managed, 399 Handles, 133 Threads
11/23 07:38:48 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
11/23 07:38:48 Trace: [push] connecting to 34.75.144.165:9204
11/23 07:38:48 Trace: [push] connected
11/23 07:38:49 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 19:48] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:38:54 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 19:53] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:38:54 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:38:58 Trace: [roonapi] [apiclient 192.168.1.14:56820] CONTINUE Changed {"zones_changed":[{"zone_id":"16014cc443571ee141e279a1e1986dc80216","display_name":"Woonkamer (Roon)","outputs":[{"output_id":"17014cc443571ee141e279a1e1986dc80216","zone_id":"16014cc443571ee141e279a1e1986dc80216","can_group_with_output_ids":[],"display_name":"Woonkamer (Roon)","volume":{"type":"number","min":0,"max":100,"value":0,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":[{"control_key":"1","display_name":"TPM191E","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":22,"queue_time_remaining":9386,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":4,"length":245,"one_line":{"line1":"We Come Undone - O.S.I."},"two_line":{"line1":"We Come Undone","line2":"O.S.I."},"three_line":{"line1":"We Come Undone","line2":"O.S.I.","line3":"Blood"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3858edbbcb63f1190d6a2a859ab786e04359fb6ce07a1376395b23f1dd8d7c9fafa4552e5616cdfb5f116e9232b91be6d502feae33a8dc0eab2d9be80451dcaa94f","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d109847ccc881c16a80820b7beb3a26158b8051b2ddb4e9ec9e450c395436b8f9cd1a179fba06a5a0ad435ffead5ba90d573d6be085fadbcc14e97454b598eedb33acc6ae7f0b63212d633132cadcab190"]}}]}
11/23 07:38:58 Trace: [roonapi] [apiclient 192.168.1.14:56820] CONTINUE Changed {"outputs_changed":[{"output_id":"17014cc443571ee141e279a1e1986dc80216","zone_id":"16014cc443571ee141e279a1e1986dc80216","can_group_with_output_ids":[],"display_name":"Woonkamer (Roon)","volume":{"type":"number","min":0,"max":100,"value":0,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":[{"control_key":"1","display_name":"TPM191E","supports_standby":false,"status":"indeterminate"}]}]}
11/23 07:38:59 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 19:58] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:38:59 Info: [stats] 23886mb Virtual, 1878mb Physical, 804mb Managed, 400 Handles, 136 Threads
11/23 07:38:59 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:39:04 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 20:03] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:39:04 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:39:09 Debug: [easyhttp] [1581] POST to https://discovery.roonlabs.net/1/query returned after 395 ms, status code: 200
11/23 07:39:09 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 20:08] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:39:09 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:39:14 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 20:13] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:39:14 Info: [stats] 23581mb Virtual, 2053mb Physical, 1098mb Managed, 400 Handles, 98 Threads
11/23 07:39:19 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 20:19] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:39:19 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=191 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:39:24 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
    Source Format=Mp3 48000/24/2 BitRate=192 Quality=LowQuality
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-5 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Output OutputType=Cast Quality=Lossless SubType= Model=Google Cast Group
    Output OutputType=Local_Other Quality=Lossless SubType= Model=
------------------------------------------------------------
11/23 07:39:25 Trace: [Keuken (Roon)] [LowQuality, 24/48 MP3 => 24/48] [100% buf] [PLAYING @ 20:24] NPO Radio 2 - Jan-Willem Start Op - BNNVARA
11/23 07:39:29 Info: [stats] 23573mb Virtual, 2136mb Physical, 905mb Managed, 400 Handles, 93 Threads
9

Sadly enough I haven’t see any reaction at this moment and the problem still getting worse.
After the last update it seems that the Roon server is eating all the NAS memory just after starting to play.
It doesn’t matter on what kind of device I listen. Is there a way to investigate the problem, because it seems that the Roon software have a memory leak.

Schermafdruk van 2021-12-20 21-00-44
Schermafdruk van 2021-12-20 21-00-442795Ă—861 88.2 KB

10

Okay, things became stranger, even in rust Roon starting spontaneously eating memory, see part of a log-file with only stats information:

12/20 16:31:46 Info: [stats] 23679mb Virtual, 1526mb Physical, 440mb Managed, 399 Handles, 92 Threads
12/20 16:32:01 Info: [stats] 23671mb Virtual, 1497mb Physical, 483mb Managed, 396 Handles, 92 Threads
12/20 16:32:16 Info: [stats] 23679mb Virtual, 1481mb Physical, 531mb Managed, 396 Handles, 94 Threads
12/20 16:32:31 Info: [stats] 23639mb Virtual, 1528mb Physical, 489mb Managed, 396 Handles, 85 Threads
12/20 16:32:46 Info: [stats] 23639mb Virtual, 1533mb Physical, 562mb Managed, 396 Handles, 86 Threads
12/20 16:33:01 Info: [stats] 23615mb Virtual, 1529mb Physical, 483mb Managed, 396 Handles, 84 Threads
12/20 16:33:16 Info: [stats] 23647mb Virtual, 1910mb Physical, 776mb Managed, 396 Handles, 90 Threads
12/20 16:33:31 Info: [stats] 23647mb Virtual, 2043mb Physical, 810mb Managed, 396 Handles, 89 Threads
12/20 16:33:46 Info: [stats] 23647mb Virtual, 2082mb Physical, 724mb Managed, 396 Handles, 89 Threads
12/20 16:34:01 Info: [stats] 23623mb Virtual, 2088mb Physical, 1177mb Managed, 396 Handles, 84 Threads
12/20 16:34:16 Info: [stats] 23623mb Virtual, 2168mb Physical, 989mb Managed, 396 Handles, 87 Threads
12/20 16:34:31 Info: [stats] 23671mb Virtual, 2222mb Physical, 1431mb Managed, 396 Handles, 90 Threads
12/20 16:34:46 Info: [stats] 23695mb Virtual, 2255mb Physical, 1165mb Managed, 397 Handles, 96 Threads
12/20 16:35:01 Info: [stats] 23695mb Virtual, 2266mb Physical, 904mb Managed, 396 Handles, 96 Threads
12/20 16:35:16 Info: [stats] 23695mb Virtual, 2413mb Physical, 1172mb Managed, 396 Handles, 94 Threads
12/20 16:35:31 Info: [stats] 23687mb Virtual, 2494mb Physical, 1071mb Managed, 397 Handles, 95 Threads
12/20 16:35:46 Info: [stats] 23687mb Virtual, 2495mb Physical, 1477mb Managed, 396 Handles, 95 Threads
11

@kevin, Why aren’t no further reactions on my posts? The problem still exist and I think that more people are affected with this problem, but the most people won’t even notice. I’m really disappointed in in the support and hoping that you can seriously investigate the problem, because the memory leak is a real Roon problem.

1 Like
12

I found out, that the memroy consumption grows after an connection error of the RAATServer, and all of these connection error is related to the Windows client or Android client. Thew Windows client is used to control Roon and the android client is situated on my mobile phone for controlling Roon and sometimes to listen directly the music on the device.

What I see on the client is that it takes a long time before the client recieve all the information on the display after an wake-up. Sometimes I restart the client. f i connect to Roon with a different client I receive directly all the necessary information. It is not that the server is overloaded.

13

Schermafdruk van 2022-01-13 18-16-52
Schermafdruk van 2022-01-13 18-16-522850Ă—594 80.1 KB

Memory consumption of Roon the last week!

14

@Bart_Ratgers
Yes, I think there is a memory leak in Roon, where CPU usage is high and there is little efficiency.
It’s most likely a network sending component, a network request, which is usually seen blocking the UI, and after a batch of network requests, you’ll see That RoonServer starts to slow down.

It’s a problem that only developers can solve, but it’s hard to solve, and they seem to be so satisfied with their software that users have a high tolerance for problems.

15

I fully agree with your opinion and that what’s frustrating me, the lack of a good support and problem facility. The last reaction of support team was from november last year. I don’t understand why they using discussion forum software instead of a ticket system.

So I hope they will finally dive into this problem and solve all the buggy software. We paying for working software and for that price I expect at least reliable software.

1 Like
16

The average user doesn’t pay attention to these problems, they are content to use them, some even very basic.
You might actually be able to find and locate the problem, but you still can’t solve the problem,
Because this is a compilation result, you can’t interfere. Can only wait for official support.

I don’t think your request is too much, paid software has a reason to require good technical support, and its price is really expensive.
You have observed and provided relevant information, and there are not many users like you who have real experience in the relevant field.
To be blunt, most of them are small white users who can’t even provide relevant information.

The software does some things well, which is why people are willing to pay for it.
But as a server, it is obviously not robust, rigorous and efficient, which is related to the level of development and attitude.
Finding excuses or ignoring that is not good performance because the software being used as a server,
It can almost really run all year round under high pressure, because that’s what the server is all about, providing high-quality, uninterrupted service.
RoonServer explicitly does not.

17

After 9 days not any respons and the problem still exist. More people dealing with the same issues:

When they will start to solve this issue?

18

To tackle this issue I have try to start over again with a clean database. This didn’t resolve the problem. After futher investigation I found some pattern that probably start the memory occupation consming.

Al the memory issues stating with:

01/23 13:14:52 Trace: [raat] [sood] Refreshing device list
01/23 13:14:52 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=306e60f5-fca1-4a92-bac8-3e732a49ab18 BrokerName='meterkast' }
01/23 13:14:52 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800884 DisplayValue='1.8 (build 884) stable' Branch='stable' } }
01/23 13:14:52 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)

01/25 08:29:06 Trace: [raatserver] [sood] Refreshing device list
01/25 08:29:06 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=306e60f5-fca1-4a92-bac8-3e732a49ab18 BrokerName='meterkast' }
01/25 08:29:06 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='Error' HasChangeLog=False CurrentVersion={ MachineValue=100800884 DisplayValue='1.8 (build 884) stable' Branch='stable' } }
01/25 08:29:06 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)

01/26 07:17:18 Trace: [raat] [sood] Refreshing device list
01/26 07:17:18 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=306e60f5-fca1-4a92-bac8-3e732a49ab18 BrokerName='meterkast' }
01/26 07:17:18 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='Error' HasChangeLog=False CurrentVersion={ MachineValue=100800884 DisplayValue='1.8 (build 884) stable' Branch='stable' } }
01/26 07:17:18 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)

And it is only triggered by the Android and Windows apps that act as a remote and conecting to the RAAT Server. So I suspect the RAAT server have some connection problem.

@kevin, Is there a way to debug thje ROON application?

19

Hey @Bart_Ratgers, thanks for providing these timestamps – I’m going to have the team take a closer look at your logs to see if we notice a pattern. The difference between your issue and others is how quickly the memory ramps up, it seems to happen over just a couple of hours.

Have you noticed if the issue persists after we released Build 898 yesterday?

20

Hello Kevin, thank you for your respons. Sadly enough the new version shows also a spike, direct after starting the Windows client:


01/31 22:13:09 Info: [brokerserver] Client connected: 192.168.1.192:34940
01/31 22:13:09 Trace: [raat] [sood] Refreshing device list
01/31 22:13:09 Trace: [raatserver] [sood] Refreshing device list
01/31 22:13:09 Trace: [raat] RAATServer discovered: RaatServer LENOVO-T540P @ 192.168.1.192:9200
01/31 22:13:09 Info: [raatserver] GOT SERVER b548bcef-f355-b4d7-10d2-f4fc827ce642::d945e4c9-fd76-431b-93f0-ecad1b6f0e49 @ 192.168.1.192:9200 LENOVO-T540P PROTOVER=1 RAATVER=1.1.38 
01/31 22:13:09 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
01/31 22:13:09 Trace: [raatserver] [RaatServer LENOVO-T540P @ 192.168.1.192:9200] connecting (attempt 1)
01/31 22:13:09 Trace: [push] retrying connection in 92091ms
01/31 22:13:09 Trace: [raatserver] [RaatServer LENOVO-T540P @ 192.168.1.192:9200] connected
01/31 22:13:09 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
01/31 22:13:09 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=306e60f5-fca1-4a92-bac8-3e732a49ab18 BrokerName='meterkast' }
01/31 22:13:09 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800898 DisplayValue='1.8 (build 898) stable' Branch='stable' } }
01/31 22:13:09 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "name": "System Output", "type": "wasapi", "is_system_output": true, "config": {"output": {"name": "System Output", "type": "wasapi", "device": "default"}, "unique_id": "3a13c7b8-abfc-0768-9ece-67819dbcc655", "volume": {"type": "wasapi", "device": "default"}, "external_config": {}}}, {"device_id": "{0.0.0.00000000}.{FD47D9CC-4218-4135-9CE2-0C195C87405B}", "name": "PulseAudio", "type": "wasapi", "config": {"output": {"name": "PulseAudio", "type": "wasapi", "device": "{0.0.0.00000000}.{FD47D9CC-4218-4135-9CE2-0C195C87405B}"}, "unique_id": "3e1afcdf-f753-87c3-3437-078eaae0b13f", "volume": {"type": "wasapi", "device": "{0.0.0.00000000}.{FD47D9CC-4218-4135-9CE2-0C195C87405B}"}, "external_config": {}}}]}
01/31 22:13:09 Info: [raatserver] GOT DEVICE d945e4c9-fd76-431b-93f0-ecad1b6f0e49::default Type=wasapi Name=System Output 
01/31 22:13:09 Info: [raatserver] GOT DEVICE d945e4c9-fd76-431b-93f0-ecad1b6f0e49::{0.0.0.00000000}.{FD47D9CC-4218-4135-9CE2-0C195C87405B} Type=wasapi Name=PulseAudio 
01/31 22:13:10 Debug: [easyhttp] [119] POST to https://discovery.roonlabs.net/1/query returned after 1027 ms, status code: 200
01/31 22:13:10 Debug: [easyhttp] [120] GET to https://messaging.roonlabs.net/1/api/messages/6c315745-58c4-47c6-b73c-24b8acdd8f3c returned after 423 ms, status code: 200
01/31 22:13:11 Info: [stats] 24250mb Virtual, 1197mb Physical, 711mb Managed, 381 Handles, 199 Threads
01/31 22:13:19 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
01/31 22:13:19 Trace: [push] connecting to 35.227.110.21:9204
01/31 22:13:19 Trace: [push] connected
01/31 22:13:26 Info: [stats] 24274mb Virtual, 1513mb Physical, 1052mb Managed, 382 Handles, 202 Threads

It starting again to consume all memory:

01/31 22:12:56 Info: [stats] 23391mb Virtual, 1187mb Physical, 687mb Managed, 380 Handles, 89 Threads
01/31 22:13:11 Info: [stats] 24250mb Virtual, 1197mb Physical, 711mb Managed, 381 Handles, 199 Threads
01/31 22:13:26 Info: [stats] 24274mb Virtual, 1513mb Physical, 1052mb Managed, 382 Handles, 202 Threads
01/31 22:13:28 Debug: [music/profilestats] Computed stats in 133ms, got 50 top albums, 50 top performers, 94 genres
01/31 22:13:28 Debug: [music/profilestats] Computed stats in 119ms, got 50 top albums, 50 top performers, 94 genres
01/31 22:13:28 Debug: [music/profilestats] Computed stats in 115ms, got 50 top albums, 50 top performers, 94 genres
01/31 22:13:34 Debug: [music/profilestats] Computed stats in 116ms, got 50 top albums, 50 top performers, 94 genres
01/31 22:13:41 Info: [stats] 23495mb Virtual, 1592mb Physical, 930mb Managed, 401 Handles, 102 Threads
01/31 22:13:56 Info: [stats] 23455mb Virtual, 1660mb Physical, 642mb Managed, 401 Handles, 96 Threads
01/31 22:14:11 Info: [stats] 23423mb Virtual, 1660mb Physical, 958mb Managed, 401 Handles, 92 Threads
01/31 22:14:21 Debug: [music/profilestats] Computed stats in 276ms, got 50 top albums, 50 top performers, 96 genres
01/31 22:14:22 Debug: [music/profilestats] Computed stats in 142ms, got 50 top albums, 50 top performers, 96 genres
01/31 22:14:22 Debug: [music/profilestats] Computed stats in 126ms, got 50 top albums, 50 top performers, 96 genres
01/31 22:14:22 Debug: [music/profilestats] Computed stats in 122ms, got 50 top albums, 50 top performers, 96 genres
01/31 22:14:26 Info: [stats] 23448mb Virtual, 1821mb Physical, 673mb Managed, 404 Handles, 99 Threads
01/31 22:14:41 Info: [stats] 23448mb Virtual, 1903mb Physical, 699mb Managed, 404 Handles, 99 Threads
01/31 22:14:56 Info: [stats] 23448mb Virtual, 1904mb Physical, 1095mb Managed, 404 Handles, 99 Threads
01/31 22:15:11 Info: [stats] 23432mb Virtual, 1912mb Physical, 831mb Managed, 404 Handles, 93 Threads
01/31 22:15:26 Info: [stats] 23433mb Virtual, 1921mb Physical, 1266mb Managed, 398 Handles, 97 Threads
01/31 22:15:41 Info: [stats] 23433mb Virtual, 1947mb Physical, 930mb Managed, 399 Handles, 93 Threads
01/31 22:15:56 Info: [stats] 23449mb Virtual, 1960mb Physical, 1419mb Managed, 397 Handles, 99 Threads
01/31 22:16:11 Info: [stats] 23449mb Virtual, 1956mb Physical, 951mb Managed, 381 Handles, 97 Threads
01/31 22:16:26 Info: [stats] 23489mb Virtual, 1966mb Physical, 1126mb Managed, 381 Handles, 104 Threads
01/31 22:16:41 Info: [stats] 23465mb Virtual, 2027mb Physical, 867mb Managed, 382 Handles, 97 Threads
01/31 22:16:56 Info: [stats] 23457mb Virtual, 2069mb Physical, 1057mb Managed, 381 Handles, 96 Threads
01/31 22:17:11 Info: [stats] 23441mb Virtual, 2070mb Physical, 1380mb Managed, 381 Handles, 94 Threads
01/31 22:17:26 Info: [stats] 23473mb Virtual, 2072mb Physical, 1310mb Managed, 381 Handles, 102 Threads
21

Just an Updtate about the memory consumption error. I can commit that the latest update improve the symptoms. I notices that the memory consumption trggered by two scenario’s and both scenario’s are related to the sleep status of the client.

  1. Scenario 1: A windows client that awakes after the PC resumed from a sleep status. In that case the client can’t update/refresh the screen and somtimes it’s resulting in a memroy consumption process at server side.
  2. Scenario 2: A Android client that awakes or activates while the mobile device using another Access Point. The result is the same as the windows client. The app can’t update/refresh the screen. Prior to the Build 918, the hanging client resulting in triggering the memory consumption process, but now, the most of the time the memrory consumption process isn’t triggering.

So I see a big progress on symptom control, bus I think the real problem isn’t resolved. So I think that the reliability of the Roon server can be improved by solving the issues in cases that the client reconnect to the network.