Aug 26 18:50:21 volumio ntpd[1215]: CLOCK: time stepped by 7941175.534514
Aug 26 18:50:21 volumio ntpd[1215]: CLOCK: time changed from 2026-05-26 to 2026-08-26
Aug 26 18:50:21 volumio ntpd[1215]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Aug 26 18:50:21 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service...
Aug 26 18:50:21 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Aug 26 18:50:21 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats.
Aug 26 18:50:21 volumio systemd[1]: e2scrub_all.service: Deactivated successfully.
Aug 26 18:50:21 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Aug 26 18:50:21 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully.
Aug 26 18:50:21 volumio volumio[1835]: info: Discovery: A device disappeared from network
Aug 26 18:50:21 volumio volumio[1835]: info: Discovery: A device disappeared from network
Aug 26 18:50:21 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Aug 26 18:50:21 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service.
Aug 26 18:50:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Aug 26 18:50:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:21 volumio go-librespot[2366]: go-librespot daemon starting...
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=info msg="running go-librespot 0.7.1"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="app state loaded"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=info msg="zeroconf server listening on port 37097"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="obtained new client token: AAGhMWAH5ItVIag8jYBplqSaf2x2XjSOX7mIreKTBvL17aQbRapaNVh52N6p/ft/+LK20qrTWXZvt1rHtU2n0t/h/3jyRG0iASEI1Yu//fsZnuRq3+boU7WwiAqrznb3RByGIUEvt9o6gSF/YuH/sLsLUm7X0N2P42gY1+bGEzjkoolDwEuugUeg8kQeM0c5Rxk7zzfCjLNa0dYQtosAEHI9zzBcDeAxttxm46oD6kz0rDSFVIU7s2TulQ=="
Aug 26 18:50:21 volumio volumio[1835]: info: Discovery: adding e51229ad-bf14-4680-96b5-42b6b68acfc3
Aug 26 18:50:21 volumio volumio[1835]: info: Discovery: Found device Volumio
Aug 26 18:50:21 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:21 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 26 18:50:21 volumio volumio[1835]: info: Discovery: this is already registered, e51229ad-bf14-4680-96b5-42b6b68acfc3
Aug 26 18:50:21 volumio volumio[1835]: info: Discovery: Found device Volumio
Aug 26 18:50:21 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:21 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="completed keyexchange"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=debug msg="completed challenge"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=info msg="authenticated AP" username="te*********67"
Aug 26 18:50:21 volumio go-librespot[2375]: time="2026-08-26T18:50:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 26 18:50:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 26 18:50:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 26 18:50:21 volumio nmbd[1276]: [2026/08/26 18:50:21.974293, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Aug 26 18:50:21 volumio nmbd[1276]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.68.29 for name WORKGROUP<1d>.
Aug 26 18:50:21 volumio nmbd[1276]: This response was from IP 192.168.68.68, reporting an IP address of 192.168.68.68.
Aug 26 18:50:22 volumio volumio[1835]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Aug 26 18:50:23 volumio volumio[1835]: info: Initializing connection to go-librespot Websocket
Aug 26 18:50:23 volumio volumio[1835]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 26 18:50:24 volumio volumio[1835]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Aug 26 18:50:24 volumio volumio[1835]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Aug 26 18:50:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Aug 26 18:50:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:24 volumio go-librespot[2462]: go-librespot daemon starting...
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=info msg="running go-librespot 0.7.1"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="app state loaded"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:24 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 26 18:50:24 volumio volumio[1835]: info: Received Get System Info
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 26 18:50:24 volumio volumio[1835]: info: Discovery: Getting this device information
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:24 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:24 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:24 volumio volumio[1835]: info: Listing playlists
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 26 18:50:24 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=info msg="zeroconf server listening on port 33923"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="obtained new client token: AAFbDMShZuK0ljt66EcvpQ7OsCJfLV1DrkgMlt6bZQ9McuCkdS2EOQX0wFDK5aW1HBCAa9ibUp1FzxmFhJ8HBNqHF2aS8LrY4dkddywjW/UEXQ+Ogz/9usZ1sAf8Sr0lb/scedh3qj2VDOMHOXHpxswnWYPyMJLuaNDokl9y8TvapgZs+jOGhU3YJwdcwC62r//7FCpTBU2wpGbdymYkpAgS/Br+Bl6fuzzIcNLG5GyUevXfnHrwPkij3A=="
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="completed keyexchange"
Aug 26 18:50:24 volumio go-librespot[2463]: time="2026-08-26T18:50:24+01:00" level=debug msg="completed challenge"
Aug 26 18:50:25 volumio go-librespot[2463]: time="2026-08-26T18:50:25+01:00" level=info msg="authenticated AP" username="te*********67"
Aug 26 18:50:25 volumio go-librespot[2463]: time="2026-08-26T18:50:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 26 18:50:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 26 18:50:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 26 18:50:25 volumio volumio[1835]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 26 18:50:25 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Aug 26 18:50:25 volumio sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 26 18:50:25 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:25 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:25 volumio sudo[2491]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 26 18:50:25 volumio sudo[2491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:25 volumio sudo[2491]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:25 volumio sudo[2495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 26 18:50:25 volumio sudo[2495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:25 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:25 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 26 18:50:25 volumio volumio[1835]: info: camilladsp service started and running in background, instance 1
Aug 26 18:50:25 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp loaded
Aug 26 18:50:25 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp - --[object Object]
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp - Reporting Fusion DSP Enabled
Aug 26 18:50:25 volumio volumio[1835]: info: Adding Signal Path Element [object Object]
Aug 26 18:50:25 volumio volumio[1835]: info: Adding fusiondspeq DSP Signal Path Element
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp - ---- installed callbackRead (debounced 200ms)
Aug 26 18:50:25 volumio volumio[1835]: info: FusionDsp - PEQ graph server listening on port 10015
Aug 26 18:50:25 volumio sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Aug 26 18:50:25 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:25 volumio volumio[1835]: error: FusionDsp - Reload WebSocket error: [object Object]
Aug 26 18:50:25 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon...
Aug 26 18:50:25 volumio dhcpcd[915]: timed out
Aug 26 18:50:25 volumio sh[898]: timed out
Aug 26 18:50:25 volumio sh[840]: ifup: failed to bring up eth0
Aug 26 18:50:25 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Aug 26 18:50:25 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin bluetooth to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin multiroom to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin metavolumio to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin cd_controller to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin qobuzconnect to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin smart_inputs to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: Adding plugin tidalconnect to MyMusic Plugins
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:26 volumio volumio[1835]: info: Starting MyVolumio Remote Streaming Endpoints
Aug 26 18:50:26 volumio volumio[1835]: info: MyVolumio login type: Token
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 26 18:50:26 volumio volumio[1835]: info: Streaming services startup
Aug 26 18:50:26 volumio volumio[1835]: info: Starting Streaming Daemon
Aug 26 18:50:26 volumio sudo[2525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 26 18:50:26 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:26 volumio volumio[1835]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 26 18:50:26 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 26 18:50:26 volumio volumio[1835]: info: Received Get System Info
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 26 18:50:26 volumio volumio[1835]: info: Discovery: Getting this device information
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:26 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 26 18:50:26 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Aug 26 18:50:26 volumio volumio[1835]: error: Cannot start Volumio Streaming Daemon
Aug 26 18:50:26 volumio volumio[1835]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 26 18:50:26 volumio volumio[1835]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 26 18:50:26 volumio volumio[1835]: info: Initializing connection to go-librespot Websocket
Aug 26 18:50:26 volumio volumio[1835]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 26 18:50:26 volumio volumio[1835]: error: MyVolumio Custom Token format not valid, refreshing it
Aug 26 18:50:27 volumio volumio[1835]: info: MyVolumio login type: Token
Aug 26 18:50:27 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Aug 26 18:50:27 volumio volumio[1835]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 26 18:50:28 volumio volumio[1835]: info: MyVolumio token set successfully
Aug 26 18:50:28 volumio volumio[1835]: info: MYVOLUMIO: Adding device
Aug 26 18:50:28 volumio volumio[1835]: info: MYVOLUMIO: Evaluating Server
Aug 26 18:50:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Aug 26 18:50:28 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Aug 26 18:50:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:28 volumio go-librespot[2537]: go-librespot daemon starting...
Aug 26 18:50:28 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 26 18:50:28 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 26 18:50:28 volumio sudo[2495]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=info msg="running go-librespot 0.7.1"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="app state loaded"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 26 18:50:28 volumio volumio[1835]: info: Upmpdcli Daemon Started
Aug 26 18:50:28 volumio volumio[1835]: info: MyVolumio Plan changed: premium
Aug 26 18:50:28 volumio volumio[1835]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Aug 26 18:50:28 volumio volumio[1835]: info: Removing browser output: myVolumio user plan is not superstar
Aug 26 18:50:28 volumio volumio[1835]: info: Removing audio output:
Aug 26 18:50:28 volumio volumio[1835]: info: MYVOLUMIO: Adding device
Aug 26 18:50:28 volumio volumio[1835]: info: MYVOLUMIO: Evaluating Server
Aug 26 18:50:28 volumio volumio[1835]: info: Remote config written successfully
Aug 26 18:50:28 volumio volumio[1835]: info: Starting Tunnel 1
Aug 26 18:50:28 volumio volumio[1835]: info: Starting Tunnel Connection Checker
Aug 26 18:50:28 volumio volumio[1835]: info: Completed starting MyVolumio Plugin
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=info msg="zeroconf server listening on port 46497"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="obtained new client token: AAH2bQ/q7xqUEOxatZ5j55VHPynn9R94qeZhTvsGIi3KpZdiNFkX9yKrWGMbX4//vIGnljXBdQdWuCWPwoP4D7QBnWwTmTq7XFdMDGsLoXOmoq4ZW1PBZhYPvmi/zNpPehqMzXoYhNFsP6y3gD9oIA9n4ySzwlIyhgAp+bHDla6MgdXjPWBh78JF+Uy9Jnbyn7sftXZs7Tizt49uTwXym0zmOvI28cjcbzn7fd7NRunuk8St3/0JRFJliQ=="
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="completed keyexchange"
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=debug msg="completed challenge"
Aug 26 18:50:28 volumio volumio[1835]: info: MYVolumio Device enabled
Aug 26 18:50:28 volumio volumio[1835]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Aug 26 18:50:28 volumio volumio[1835]: info: MyVolumio status changed
Aug 26 18:50:28 volumio volumio[1835]: info: Streaming services startup
Aug 26 18:50:28 volumio volumio[1835]: info: Starting Streaming Daemon
Aug 26 18:50:28 volumio sudo[2601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 26 18:50:28 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=info msg="authenticated AP" username="te*********67"
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Aug 26 18:50:28 volumio volumio[1835]: error: [MyVolumio PluginManager] Cache data is invalid!
Aug 26 18:50:28 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:28 volumio volumio[1835]: error: Cannot start Volumio Streaming Daemon
Aug 26 18:50:28 volumio volumio[1835]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 26 18:50:28 volumio volumio[1835]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 26 18:50:28 volumio go-librespot[2538]: time="2026-08-26T18:50:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 26 18:50:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 26 18:50:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 26 18:50:28 volumio volumio[1835]: info: Setting Geolocation for MyVolumio to eu3
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:28 volumio volumio[1835]: info: Setting Geolocation for MyVolumio to eu3
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:28 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:28 volumio volumio[1835]: info: Successfully Added MyVolumio device
Aug 26 18:50:29 volumio volumio[1835]: info: Successfully Added MyVolumio device
Aug 26 18:50:29 volumio volumio[1835]: info: Updating MyVolumio device info
Aug 26 18:50:29 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:29 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:29 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:29 volumio volumio[1835]: info: Initializing connection to go-librespot Websocket
Aug 26 18:50:29 volumio volumio[1835]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 26 18:50:29 volumio volumio[1835]: info: Updating MyVolumio device info
Aug 26 18:50:29 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:29 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:29 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:29 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Aug 26 18:50:29 volumio volumio[1835]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth
Aug 26 18:50:29 volumio volumio[1835]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth
Aug 26 18:50:29 volumio volumio[1835]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Aug 26 18:50:29 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Aug 26 18:50:30 volumio sudo[2605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Aug 26 18:50:30 volumio sudo[2605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2605]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: MultiRoom plugin initialized
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: STOPPING SNAPCLIENT
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Snap server stop
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: STOPPING volumioStreaming
Aug 26 18:50:30 volumio sudo[2623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Aug 26 18:50:30 volumio sudo[2623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Aug 26 18:50:30 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Aug 26 18:50:30 volumio sudo[2626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Aug 26 18:50:30 volumio sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2630]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Aug 26 18:50:30 volumio sudo[2623]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Aug 26 18:50:30 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Aug 26 18:50:30 volumio sudo[2626]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Aug 26 18:50:30 volumio volumio[1835]: info: Preparing to generate the ALSA configuration file
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Aug 26 18:50:30 volumio volumio[1835]: info: Successfully Updated MyVolumio device
Aug 26 18:50:30 volumio volumio[1835]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 26 18:50:30 volumio volumio[1835]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 26 18:50:30 volumio volumio[1835]: info: Reading ALSA contributions from plugins.
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Removed streaming files
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: volumioStreaming STOPPED
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: SNAPSERVER STOPPED
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: SNAPCLIENT STOPPED
Aug 26 18:50:30 volumio volumio[1835]: info: Asound.conf file written
Aug 26 18:50:30 volumio sudo[2637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Aug 26 18:50:30 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Aug 26 18:50:30 volumio volumio[1835]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Aug 26 18:50:30 volumio volumio[1835]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Aug 26 18:50:30 volumio volumio[1835]: info: Output device has changed, restarting MPD
Aug 26 18:50:30 volumio sudo[2643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 26 18:50:30 volumio volumio[1835]: info: Output device has changed, restarting Shairport Sync
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:30 volumio sudo[2645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 26 18:50:30 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio volumio[1835]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 26 18:50:30 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Aug 26 18:50:30 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Pushing multiroomSync output for this device
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Pushing multiroomSync output
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Aug 26 18:50:30 volumio volumio[1835]: info: Adding METAVOLUMIO REST API Endpoints
Aug 26 18:50:30 volumio volumio[1835]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Aug 26 18:50:30 volumio volumio[1835]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Aug 26 18:50:30 volumio volumio[1835]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Aug 26 18:50:30 volumio volumio[1835]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Aug 26 18:50:30 volumio volumio[1835]: info: Adding inputs REST Endpoints
Aug 26 18:50:30 volumio volumio[1835]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Aug 26 18:50:30 volumio volumio[1835]: info: Scanning Audio Inputs
Aug 26 18:50:30 volumio systemd[1]: mpd.service: Deactivated successfully.
Aug 26 18:50:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 26 18:50:30 volumio systemd[1]: mpd.socket: Deactivated successfully.
Aug 26 18:50:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 26 18:50:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 26 18:50:30 volumio bluetoothd[1022]: Path / reserved for Adv Monitor app :1.42
Aug 26 18:50:30 volumio volumio[1835]: info: Checking against Known Cards name
Aug 26 18:50:30 volumio volumio[1835]: info: Adding Server instance for streaming
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Aug 26 18:50:30 volumio bluetoothd[1022]: Adv Monitor app :1.42 disconnected from D-Bus
Aug 26 18:50:30 volumio volumio[1835]: error: Hi Res Audio Failed Login: Missing Login Data
Aug 26 18:50:30 volumio volumio[1835]: info: Adding HIGHRESAUDIO REST API Endpoints
Aug 26 18:50:30 volumio volumio[1835]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Aug 26 18:50:30 volumio volumio[1835]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting
Aug 26 18:50:30 volumio volumio[1835]: info: Adding TIDAL REST API Endpoints
Aug 26 18:50:30 volumio volumio[1835]: info: Stopping AccessToken refresher cron for QOBUZ
Aug 26 18:50:30 volumio sudo[2664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 26 18:50:30 volumio sudo[2664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 26 18:50:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 26 18:50:30 volumio volumio[1835]: info: AccessToken refresher cron started for QOBUZ
Aug 26 18:50:30 volumio volumio[1835]: info: Adding QOBUZ REST API Endpoints
Aug 26 18:50:30 volumio volumio[1835]: info: Successfully Updated MyVolumio device
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on
Aug 26 18:50:30 volumio volumio[1835]: info: MPD Permissions set
Aug 26 18:50:30 volumio sudo[2664]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio sudo[2668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Aug 26 18:50:30 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio sudo[2666]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 26 18:50:30 volumio sudo[2666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 26 18:50:30 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 26 18:50:30 volumio sudo[2666]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumiobt[2678]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 26 18:50:30 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 26 18:50:30 volumio sudo[2680]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 26 18:50:30 volumio sudo[2680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio sudo[2680]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio sudo[2682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 26 18:50:30 volumio sudo[2682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Aug 26 18:50:30 volumio volumio[1835]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Aug 26 18:50:30 volumio volumio[1835]: info: Starting Shairport Sync
Aug 26 18:50:30 volumio sudo[2682]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumiobt[2685]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 26 18:50:30 volumio sudo[2686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio bluetoothd[1022]: Adv Monitor app :1.43 disconnected from D-Bus
Aug 26 18:50:30 volumio volumiobt[2689]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 26 18:50:30 volumio sudo[2686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumiobt[2690]: [176B blob data]
Aug 26 18:50:30 volumio volumiobt[2690]: [157B blob data]
Aug 26 18:50:30 volumio volumiobt[2690]: [157B blob data]
Aug 26 18:50:30 volumio volumiobt[2690]: [157B blob data]
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumiobt[2690]: [bluetoothctl]> discoverable on
Aug 26 18:50:30 volumio volumiobt[2690]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended
Aug 26 18:50:30 volumio volumiobt[2690]: [bluetoothctl]> pairable on
Aug 26 18:50:30 volumio volumiobt[2690]: [113B blob data]
Aug 26 18:50:30 volumio volumiobt[2690]: [131B blob data]
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio bluetoothd[1022]: Path / reserved for Adv Monitor app :1.44
Aug 26 18:50:30 volumio bluetoothd[1022]: Adv Monitor app :1.44 disconnected from D-Bus
Aug 26 18:50:30 volumio volumiobt[2690]: [bluetoothctl]>
Aug 26 18:50:30 volumio volumiobt[2692]: INFO [BTSTART] Registering Bluetooth agent...
Aug 26 18:50:30 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 26 18:50:30 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 26 18:50:30 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 26 18:50:30 volumio systemd[1]: shairport-sync.service: Consumed 1.524s CPU time.
Aug 26 18:50:30 volumio volumiobt[2693]: [NEW] Media /org/bluez/hci0
Aug 26 18:50:30 volumio volumiobt[2693]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb
Aug 26 18:50:30 volumio volumiobt[2693]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb
Aug 26 18:50:30 volumio volumiobt[2693]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb
Aug 26 18:50:30 volumio bluetoothd[1022]: Adv Monitor app :1.45 disconnected from D-Bus
Aug 26 18:50:30 volumio volumiobt[2695]: No agent is registered
Aug 26 18:50:30 volumio volumiobt[2695]: [NEW] Media /org/bluez/hci0
Aug 26 18:50:30 volumio volumiobt[2695]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb
Aug 26 18:50:30 volumio volumiobt[2695]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb
Aug 26 18:50:30 volumio volumiobt[2695]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb
Aug 26 18:50:30 volumio bluetoothd[1022]: Adv Monitor app :1.46 disconnected from D-Bus
Aug 26 18:50:30 volumio volumiobt[2696]: INFO [BTSTART] Agent registered successfully.
Aug 26 18:50:30 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 26 18:50:30 volumio volumiobt[2697]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 26 18:50:30 volumio sudo[2686]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon.
Aug 26 18:50:30 volumio volumio[1835]: info: Shairport-Sync Started
Aug 26 18:50:30 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Found cast device: LS03D-e98160e4bd2f453453f116985d44f73e
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:30 volumio volumio[1835]: info: MRS: Found cast device: LS03D-da1cfe89d72dc2392698640ba471be62
Aug 26 18:50:30 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:31 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Connecting to system D-Bus
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Connected to system D-Bus
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 bluezutils [INFO] Found adapter at: /org/bluez/hci0
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Set DiscoverableTimeout to infinite
Aug 26 18:50:31 volumio mpd[2679]: 2026-08-26T18:50:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 26 18:50:31 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Aug 26 18:50:31 volumio sudo[2645]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:31 volumio volumio[1835]: info: Adding audio output:
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Enabled Discoverable mode
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Agent registered at /local/a2dpagent
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] Agent set as default
Aug 26 18:50:31 volumio volumiobt[2698]: 2026-08-26 18:50:31 a2dp-agent [INFO] A2DP agent running, waiting for connections...
Aug 26 18:50:31 volumio volumio[1835]: error: updateQueue error: null
Aug 26 18:50:31 volumio upmpdcli[2719]: writing RSA key
Aug 26 18:50:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Aug 26 18:50:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 26 18:50:31 volumio go-librespot[2733]: go-librespot daemon starting...
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=info msg="running go-librespot 0.7.1"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=debug msg="app state loaded"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=info msg="zeroconf server listening on port 37087"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Aug 26 18:50:31 volumio go-librespot[2735]: time="2026-08-26T18:50:31+01:00" level=debug msg="obtained new client token: AAGnRuLLZ7Ib61+x+c2q67ziG6TBj+ylVUpZygnRgp/fBZI6rx5TJlcucFrVItvkY0Faa0Xyz+8oW9k+Y67HANxdULgV3jy43F/HGu/vZxYQudvd1Ng8k/ezVlLcbsmKE1gLtd29bnl28dEN2ACmStPVdh6hxDaapq7HbeNVxeWEqrwV4t+uKf+Z/s9Ve3V2+SW1AdbbNj9dkBDYOQxtH7kt0aGGngAqkSmgRJVVqyeAquThM9b2Xdtjaw=="
Aug 26 18:50:32 volumio volumio[1835]: info: Initializing connection to go-librespot Websocket
Aug 26 18:50:32 volumio go-librespot[2735]: time="2026-08-26T18:50:32+01:00" level=debug msg="new websocket client"
Aug 26 18:50:32 volumio volumio[1835]: info: Connection to go-librespot Websocket established
Aug 26 18:50:32 volumio go-librespot[2735]: time="2026-08-26T18:50:32+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070"
Aug 26 18:50:32 volumio go-librespot[2735]: time="2026-08-26T18:50:32+01:00" level=debug msg="completed keyexchange"
Aug 26 18:50:32 volumio go-librespot[2735]: time="2026-08-26T18:50:32+01:00" level=debug msg="completed challenge"
Aug 26 18:50:33 volumio go-librespot[2735]: time="2026-08-26T18:50:33+01:00" level=info msg="authenticated AP" username="te*********67"
Aug 26 18:50:33 volumio go-librespot[2735]: time="2026-08-26T18:50:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 26 18:50:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 26 18:50:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 26 18:50:33 volumio volumio[1835]: info: Connection to go-librespot Websocket closed
Aug 26 18:50:33 volumio sudo[2754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Aug 26 18:50:33 volumio sudo[2754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 26 18:50:33 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 26 18:50:33 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Aug 26 18:50:33 volumio sudo[2754]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:33 volumio volumio[1835]: info: Remote SSH Started
Aug 26 18:50:33 volumio autossh[2757]: port set to 0, monitoring disabled
Aug 26 18:50:33 volumio autossh[2757]: starting ssh (count 1)
Aug 26 18:50:33 volumio autossh[2757]: ssh child pid is 2760
Aug 26 18:50:33 volumio volumio[1835]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::volumioGetState
Aug 26 18:50:33 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:33 volumio volumiossh-tunnel[2760]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts.
Aug 26 18:50:33 volumio volumio[1835]: info: TidalConnect service stoped!
Aug 26 18:50:33 volumio volumio[1835]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Aug 26 18:50:33 volumio volumio[1835]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Aug 26 18:50:33 volumio sudo[2770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Aug 26 18:50:33 volumio sudo[2770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 26 18:50:33 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Aug 26 18:50:33 volumio sudo[2770]: pam_unix(sudo:session): session closed for user root
Aug 26 18:50:33 volumio volumio[1835]: info: Executing endpoint tc_getconfig
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Aug 26 18:50:33 volumio vtcs[2773]: STARTING TidalConnect services, version: 1.6.1
Aug 26 18:50:33 volumio vtcs[2773]: STARTED TidalConnect services.
Aug 26 18:50:33 volumio volumio[1835]: info: Executing endpoint tc_connect
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Aug 26 18:50:33 volumio volumio[1835]: info: Connecting to TidalConnect
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::servicePushState
Aug 26 18:50:33 volumio volumio[1835]: info: CoreStateMachine::pushState
Aug 26 18:50:33 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::volumioPushState
Aug 26 18:50:33 volumio volumio[1835]: info: MRS: Pushing multiroomSync output update for this device
Aug 26 18:50:33 volumio volumio[1835]: info: MRS: Pushing multiroomSync output
Aug 26 18:50:33 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:33 volumio volumio[1835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::servicePushState
Aug 26 18:50:33 volumio volumio[1835]: info: CoreStateMachine::pushState
Aug 26 18:50:33 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:33 volumio volumio[1835]: info: CoreCommandRouter::volumioPushState
Aug 26 18:50:33 volumio volumio[1835]: info: MRS: Pushing multiroomSync output update for this device
Aug 26 18:50:33 volumio volumio[1835]: info: MRS: Pushing multiroomSync output
Aug 26 18:50:33 volumio volumio[1835]: info: CorePlayQueue::getTrack 0
Aug 26 18:50:33 volumio volumio[1835]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received tidalconnect
Aug 26 18:50:33 volumio volumio[1835]: info: FusionDsp - Volumio is not playing
Aug 26 18:50:33 volumio volumio[1835]: info: FusionDsp - Clipped samples monitor stopped
Aug 26 18:50:33 volumio volumio[1835]: info: FusionDsp - Volumio is not playing
Aug 26 18:50:33 volumio volumio[1835]: info: FusionDsp - Clipped samples monitor stopped
Aug 26 18:50:34 volumio volumio[1835]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 26 18:50:35 volumio volumio[1835]: info: MRS: Getting audio outputs on start
Aug 26 18:50:35 volumio volumio[1835]: info: MRS: Requesting all other devices output
Aug 26 18:50:35 volumio volumio[1835]: info: Getting Spotify volume
Aug 26 18:50:35 volumio volumio[1835]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 26 18:50:35 volumio volumio[1835]: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 26 18:50:35 volumio volumio[1835]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Aug 26 18:50:35 volumio volumio[1835]: errno: -111,
Aug 26 18:50:35 volumio volumio[1835]: code: 'ECONNREFUSED',
Aug 26 18:50:35 volumio volumio[1835]: syscall: 'connect',
Aug 26 18:50:35 volumio volumio[1835]: address: '127.0.0.1',
Aug 26 18:50:35 volumio volumio[1835]: port: 9879,
Aug 26 18:50:35 volumio volumio[1835]: response: undefined
Aug 26 18:50:35 volumio volumio[1835]: }
Aug 26 18:50:35 volumio volumio[1835]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 26 18:50:35 volumio sudo[2817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 18:49'
Aug 26 18:50:35 volumio sudo[2817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026"
VOLUMIO_VERSION="4.119"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"