Dec 15 15:42:00 volumio go-librespot[16142]: time="2025-12-15T15:42:00+01:00" level=debug msg="obtained new client token: AAADJOgMFzWaexbWz4nHB5ZNJ53OceoD1Mg8e2n6uOnq1oYc+I8MMWw6gJfhcPIIHXl85UBklNk1sz4hel5yS1R/IfPAIovxwIc4cw6B+K1yhvzJdtSgWVUTHVbjQZPjmr4KmP8cVreQgzNkUHrWqXv6bn78iQFEaICZVFKiUtfuD45Qaeqctume5CJAfJ1IA/xH2BC66GF2o7iVV9zP7MC6JQCfn4BKkD4uUScTzWMwe5RMd506+XiM5A==" Dec 15 15:42:00 volumio go-librespot[16142]: time="2025-12-15T15:42:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:00 volumio go-librespot[16142]: time="2025-12-15T15:42:00+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:00 volumio go-librespot[16142]: time="2025-12-15T15:42:00+01:00" level=debug msg="completed challenge" Dec 15 15:42:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:00 volumio volumio-remote-updater[682]: [2025-12-15 15:42:00] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Dec 15 15:42:00 volumio volumio-remote-updater[682]: [2025-12-15 15:42:00] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Dec 15 15:42:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 15 15:42:00 volumio systemd[1]: volumio.service: Consumed 1min 16.193s CPU time. Dec 15 15:42:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:42:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:42:00 volumio go-librespot[16142]: time="2025-12-15T15:42:00+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 314. Dec 15 15:42:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:42:00 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 15 15:42:00 volumio systemd[1]: volumio.service: Consumed 1min 16.193s CPU time. Dec 15 15:42:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 15 15:42:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:42:00 volumio go-librespot[16142]: time="2025-12-15T15:42:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:02 volumio volumio[16162]: info: ------------------------------------------- Dec 15 15:42:02 volumio volumio[16162]: info: ----- Volumio3 ---- Dec 15 15:42:02 volumio volumio[16162]: info: ------------------------------------------- Dec 15 15:42:02 volumio volumio[16162]: info: ----- System startup ---- Dec 15 15:42:02 volumio volumio[16162]: info: ------------------------------------------- Dec 15 15:42:03 volumio volumio[16162]: info: MYVOLUMIO Environment detected Dec 15 15:42:03 volumio volumio[16162]: info: Plugin folders cleanup Dec 15 15:42:03 volumio volumio[16162]: info: Scanning into folder /volumio/app/plugins/ Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category audio_interface Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category miscellanea Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category music_service Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category plugins.json Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category system_controller Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category user_interface Dec 15 15:42:03 volumio volumio[16162]: info: Scanning into folder /data/plugins/ Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category audio_interface Dec 15 15:42:03 volumio volumio[16162]: info: Scanning category music_service Dec 15 15:42:03 volumio volumio[16162]: info: Plugin folders cleanup completed Dec 15 15:42:03 volumio volumio[16162]: info: ------------------------------------------- Dec 15 15:42:03 volumio volumio[16162]: info: ----- Core plugins startup ---- Dec 15 15:42:03 volumio volumio[16162]: info: ------------------------------------------- Dec 15 15:42:03 volumio volumio[16162]: info: Loading plugins from folder /volumio/app/plugins/ Dec 15 15:42:03 volumio volumio[16162]: info: Adding plugin upnp to MyMusic Plugins Dec 15 15:42:03 volumio volumio[16162]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 15 15:42:03 volumio volumio[16162]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 15 15:42:03 volumio volumio[16162]: info: Loading plugins from folder /data/plugins/ Dec 15 15:42:03 volumio volumio[16162]: info: Loading plugin "system"... Dec 15 15:42:03 volumio volumio[16162]: info: Loading plugin "appearance"... Dec 15 15:42:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 15 15:42:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:03 volumio go-librespot[16189]: go-librespot daemon starting... Dec 15 15:42:03 volumio go-librespot[16190]: time="2025-12-15T15:42:03+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:03 volumio go-librespot[16190]: time="2025-12-15T15:42:03+01:00" level=debug msg="app state loaded" Dec 15 15:42:03 volumio go-librespot[16190]: time="2025-12-15T15:42:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=info msg="zeroconf server listening on port 41699" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="obtained new client token: AAAJUhXtJelKF/v6DxzwUSEd8SnRLsseH2j6j3s8M+Wo6lRiKcJ+OO4ramJmJvrcZ2sFf4GAodvNXEylmuvtlaCy5VrrZ4RgxyFDbJ4pMOwHBG+W+0k8wCb+BWlYurlXgrH/87Kfjbd83bc3hWl87bqD/nR/g1sgOuWQGEu9EmY361RD6CRx1CZt29zzOJZ3ymOzge+58pM2JtalKfHezRplUrxKuKNZIht6D8MqcaSmh8NF9F/1vv8=" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=debug msg="completed challenge" Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "network"... Dec 15 15:42:04 volumio volumio[16162]: info: Refreshing Cached IP Addresses Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:04 volumio sudo[16198]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:42:04 volumio sudo[16198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:04 volumio sudo[16198]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:04 volumio sudo[16200]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:42:04 volumio sudo[16200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "services"... Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "alsa_controller"... Dec 15 15:42:04 volumio sudo[16200]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:04 volumio sudo[16208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 15 15:42:04 volumio sudo[16208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:04 volumio volumio[16162]: warn: Unable to locate the audio output device Fosi Audio ZD3. Please configure a valid output device. Dec 15 15:42:04 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "wizard"... Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "networkfs"... Dec 15 15:42:04 volumio volumio[16162]: info: Starting Udev Watcher for removable devices Dec 15 15:42:04 volumio volumio[16162]: info: Ignoring mount for partition: boot Dec 15 15:42:04 volumio volumio[16162]: info: Ignoring mount for partition: volumio Dec 15 15:42:04 volumio volumio[16162]: info: Ignoring mount for partition: volumio_data Dec 15 15:42:04 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "volumio_command_line_client"... Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "upnp"... Dec 15 15:42:04 volumio go-librespot[16190]: time="2025-12-15T15:42:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:04 volumio volumio[16162]: info: [1765809724907] Starting Upmpd Daemon Dec 15 15:42:04 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "my_music"... Dec 15 15:42:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:04 volumio volumio[16162]: info: Loading plugin "mpd"... Dec 15 15:42:05 volumio volumio[16162]: info: Loading plugin "upnp_browser"... Dec 15 15:42:05 volumio volumio-remote-updater[682]: [2025-12-15 15:42:05] [connect] Successful connection Dec 15 15:42:06 volumio volumio[16162]: info: Starting UPNP Browser Dec 15 15:42:06 volumio volumio[16162]: info: Loading plugin "alarm-clock"... Dec 15 15:42:06 volumio volumio[16162]: info: Loading plugin "airplay_emulation"... Dec 15 15:42:07 volumio volumio[16162]: info: Starting Shairport Sync Dec 15 15:42:07 volumio volumio[16162]: info: Loading plugin "last_100"... Dec 15 15:42:07 volumio volumio[16162]: info: Loading plugin "webradio"... Dec 15 15:42:07 volumio volumio[16162]: info: Loading plugin "i2s_dacs"... Dec 15 15:42:07 volumio volumio[16162]: info: Loading plugin "volumiodiscovery"... Dec 15 15:42:07 volumio volumio[16162]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:07 volumio volumio[16162]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:07 volumio volumio[16162]: *** WARNING *** For more information see Dec 15 15:42:07 volumio volumio[16162]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:07 volumio volumio[16162]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:07 volumio volumio[16162]: *** WARNING *** For more information see Dec 15 15:42:07 volumio node[16162]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:07 volumio node[16162]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:07 volumio node[16162]: *** WARNING *** For more information see Dec 15 15:42:07 volumio node[16162]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:07 volumio node[16162]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:07 volumio node[16162]: *** WARNING *** For more information see Dec 15 15:42:07 volumio volumio[16162]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 15 15:42:07 volumio volumio[16162]: info: Discovery: Started advertising with name: Volumio Dec 15 15:42:07 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:42:07 volumio volumio[16162]: info: Loading plugin "mixcloud"... Dec 15 15:42:07 volumio sudo[16208]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:07 volumio volumio[16162]: info: Loading plugin "spop"... Dec 15 15:42:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 15 15:42:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:08 volumio go-librespot[16231]: go-librespot daemon starting... Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="app state loaded" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=info msg="zeroconf server listening on port 39379" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="obtained new client token: AADhl6SOeRAvLrNZ3Fdn6sySwcmu/VhG1L9kYivVuoduI5Go8TNfkzF+97MppaCi/MWIK0s9EY9iFdh+ArcuWmWTXQkTfDr0uBdoWWU/fkEG+m7SYtD/zudNRJ8D3+uk+c2ob7M4DAmc3oU4Ku23qo8F854LKA6VhWceT2ZbyQaLBl29jyTEWEgmaiiTy9i3NwTuCAAKtOJvj6Xv1ynh87H4Emcf/OPqu2OGMUsz5FPPWhTpetBVrx7cMw==" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=debug msg="completed challenge" Dec 15 15:42:08 volumio go-librespot[16232]: time="2025-12-15T15:42:08+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:08 volumio volumio[16162]: info: Loading plugin "ytcr"... Dec 15 15:42:09 volumio go-librespot[16232]: time="2025-12-15T15:42:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:10 volumio volumio[16162]: info: Loading plugin "ytmusic"... Dec 15 15:42:10 volumio volumio[16162]: info: Loading plugin "outputs"... Dec 15 15:42:10 volumio volumio[16162]: info: Loading plugin "albumart"... Dec 15 15:42:10 volumio volumio[16162]: info: Plugin example_plugin is not enabled Dec 15 15:42:10 volumio volumio[16162]: info: Loading plugin "inputs"... Dec 15 15:42:10 volumio volumio[16162]: info: Loading plugin "updater_comm"... Dec 15 15:42:11 volumio volumio[16162]: info: Plugin mpdemulation is not enabled Dec 15 15:42:11 volumio volumio[16162]: info: Loading plugin "rest_api"... Dec 15 15:42:11 volumio volumio[16162]: info: Loading plugin "websocket"... Dec 15 15:42:11 volumio volumio[16162]: info: Starting Socket.io Server version 1.7.4 Dec 15 15:42:11 volumio volumio[16162]: info: Loading plugin "fusiondsp"... Dec 15 15:42:11 volumio volumio[16162]: info: Applying required configuration parameters for plugin fusiondsp Dec 15 15:42:11 volumio volumio[16243]: Forking 3 albumart workers Dec 15 15:42:11 volumio volumio[16162]: info: Loading i18n strings for locale no Dec 15 15:42:11 volumio volumio[16162]: Updating browse sources language Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::initPlayerControls Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:42:11 volumio volumio[16162]: Express server listening on port 3000 Dec 15 15:42:11 volumio volumio[16162]: [Metrics] WebUI: 9s 726.10ms Dec 15 15:42:11 volumio volumio[16162]: info: CoreStateMachine::resetVolumioState Dec 15 15:42:11 volumio volumio[16162]: info: CoreStateMachine::getcurrentVolume Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:42:11 volumio volumio[16162]: info: CoreStateMachine::pushState Dec 15 15:42:11 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:42:11 volumio volumio[16162]: info: CoreCommandRouter::volumioPushState Dec 15 15:42:11 volumio volumio[16162]: info: Volumio Network Manager: Network status updated: 1 Dec 15 15:42:12 volumio volumio[16162]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.64 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:12 volumio volumio[16162]: info: Reloading queue from file Dec 15 15:42:12 volumio volumio[16162]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.64 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 15 15:42:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 15 15:42:12 volumio volumio[16162]: info: CoreStateMachine::setRepeat null single undefined Dec 15 15:42:12 volumio volumio[16162]: info: CoreStateMachine::pushState Dec 15 15:42:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioPushState Dec 15 15:42:12 volumio volumio[16162]: info: CoreStateMachine::setRandom null Dec 15 15:42:12 volumio volumio[16162]: info: CoreStateMachine::pushState Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioPushState Dec 15 15:42:12 volumio volumio[16162]: info: Setting Device type: Raspberry PI Dec 15 15:42:12 volumio volumio[16162]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.64 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 15 15:42:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:12 volumio go-librespot[16291]: go-librespot daemon starting... Dec 15 15:42:12 volumio volumio[16162]: info: Completed loading Core Plugins Dec 15 15:42:12 volumio volumio[16162]: info: Preparing to generate the ALSA configuration file Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=debug msg="app state loaded" Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:12 volumio volumio[16162]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 15 15:42:12 volumio volumio[16162]: info: Reading ALSA contributions from plugins. Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetQueue Dec 15 15:42:12 volumio volumio[16162]: info: CoreStateMachine::getQueue Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getQueue Dec 15 15:42:12 volumio volumio[16162]: info: Listing playlists Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 15:42:12 volumio volumio[16162]: info: Received Get System Info Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 15:42:12 volumio volumio[16162]: info: Discovery: Getting this device information Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio volumio[16162]: info: Discovery: adding 8b7599b7-0a8c-410b-b912-d40cd4f4da34 Dec 15 15:42:12 volumio volumio[16162]: info: Discovery: Found device Volumio Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=info msg="zeroconf server listening on port 41533" Dec 15 15:42:12 volumio volumio[16162]: info: Discovery: this is already registered, 8b7599b7-0a8c-410b-b912-d40cd4f4da34 Dec 15 15:42:12 volumio volumio[16162]: info: Discovery: Found device Volumio Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:12 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:12 volumio volumio[16162]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:42:12 volumio volumio[16162]: info: Output device has changed, restarting MPD Dec 15 15:42:12 volumio volumio[16162]: info: Output device has changed, restarting Shairport Sync Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:12 volumio sudo[16302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:42:12 volumio sudo[16302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:12 volumio sudo[16302]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:12 volumio sudo[16304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:42:12 volumio sudo[16304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:12 volumio volumio[16162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:42:12 volumio volumio[16162]: info: ___________ START PLUGINS ___________ Dec 15 15:42:12 volumio go-librespot[16293]: time="2025-12-15T15:42:12+01:00" level=debug msg="obtained new client token: AACl2hwL6jbdQOcj/Nzk1/Px6KJf5pyBE+2C+Ltr46Wm4uEQXQjnN0mLqN1QfP5JPvK9SIthvE/lJMbLvjSGAWiLavOMJUIlQPI0KPle6x+wZGE3QV5Wx2CWx1QMsMmdF5gwMDzucvDwgYpbw5hsMPCKJbR1n12koq0MiLtjEWITMafdFfOdaEyf0a8Q99BmroHH74ziMp4u4l12J6K/ih/y99ZDL9HkP3zLPZ8rTSgw3SXGpR+BVi0G/A==" Dec 15 15:42:12 volumio volumio[16162]: info: ControllerMpd::onStart: Initializing MPD Dec 15 15:42:12 volumio volumio[16162]: info: Creating MPD Configuration file Dec 15 15:42:12 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:13 volumio volumio[16162]: info: [1765809733002] CoreMusicLibrary::Adding element Media Servers Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:13 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 15:42:13 volumio volumio[16162]: info: UPNP Browser: Client initialized successfully Dec 15 15:42:13 volumio go-librespot[16293]: time="2025-12-15T15:42:13+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 15 15:42:13 volumio sudo[16312]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:42:13 volumio sudo[16312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:13 volumio sudo[16312]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:13 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:42:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:42:13 volumio systemd[1]: mpd.service: Consumed 3.271s CPU time. Dec 15 15:42:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:42:13 volumio sudo[16314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:42:13 volumio sudo[16314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:42:13 volumio go-librespot[16293]: time="2025-12-15T15:42:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 15 15:42:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:13 volumio go-librespot[16293]: time="2025-12-15T15:42:13+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:13 volumio go-librespot[16293]: time="2025-12-15T15:42:13+01:00" level=debug msg="completed challenge" Dec 15 15:42:13 volumio volumio[16162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:42:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:42:13 volumio volumio[16162]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:42:13 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:42:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:42:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:13 volumio volumio[16162]: info: [1765809733255] CoreMusicLibrary::Adding element Last_100 Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:42:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:42:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:13 volumio volumio[16162]: info: [1765809733276] CoreMusicLibrary::Adding element Webradio Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:42:13 volumio volumio[16254]: Starting albumart workers Dec 15 15:42:13 volumio volumio[16162]: info: Initializing BBC Radios Dec 15 15:42:13 volumio go-librespot[16293]: time="2025-12-15T15:42:13+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:13 volumio volumio[16162]: info: [1765809733456] CoreMusicLibrary::Adding element Mixcloud Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:13 volumio volumio[16162]: Cannot find translation for source Mixcloud Dec 15 15:42:13 volumio volumio[16162]: info: Creating Spotify config file Dec 15 15:42:13 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:13 volumio volumio[16255]: Starting albumart workers Dec 15 15:42:13 volumio sudo[16328]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:42:13 volumio sudo[16328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:42:13 volumio sudo[16328]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:13 volumio volumio[16253]: Starting albumart workers Dec 15 15:42:13 volumio go-librespot[16293]: time="2025-12-15T15:42:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:14 volumio volumio[16162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:14 volumio volumio[16162]: info: [1765809734262] CoreMusicLibrary::Adding element YouTube Music Dec 15 15:42:14 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:14 volumio volumio[16162]: Cannot find translation for source Mixcloud Dec 15 15:42:14 volumio volumio[16162]: Cannot find translation for source YouTube Music Dec 15 15:42:14 volumio volumio[16162]: info: Loading i18n strings for locale no Dec 15 15:42:14 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 15 15:42:14 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:14 volumio volumio[16162]: info: FusionDsp - mixtype--------------------- None Dec 15 15:42:14 volumio volumio[16162]: info: Volumio Calling Home Dec 15 15:42:14 volumio volumio[16162]: info: Preparing to generate the ALSA configuration file Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:15 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 15:42:16 volumio volumio[16162]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 15 15:42:16 volumio volumio[16162]: info: Reading ALSA contributions from plugins. Dec 15 15:42:16 volumio volumio[16162]: info: MPD Permissions set Dec 15 15:42:16 volumio volumio[16162]: info: MPD Permissions set Dec 15 15:42:16 volumio sudo[16382]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:42:16 volumio sudo[16382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:16 volumio sudo[16382]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:16 volumio sudo[16384]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:42:16 volumio sudo[16384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:16 volumio volumio[16162]: info: Volumio called home Dec 15 15:42:16 volumio sudo[16384]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:16 volumio volumio[16162]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 15 15:42:16 volumio volumio[16162]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 15 15:42:16 volumio volumio[16162]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 15 15:42:16 volumio volumio[16162]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 15 15:42:16 volumio volumio[16162]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 15 15:42:16 volumio volumio[16162]: info: camilladsp service started and running in background, instance 1 Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:16 volumio volumio[16162]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for ZD3 Dec 15 15:42:16 volumio volumio[16162]: cannot open device 'volumioHw': No such device Dec 15 15:42:16 volumio volumio[16162]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 15 15:42:16 volumio volumio[16162]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for ZD3 Dec 15 15:42:16 volumio volumio[16162]: cannot open device 'volumioHw': No such device Dec 15 15:42:16 volumio volumio[16162]: info: FusionDsp loaded Dec 15 15:42:16 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:42:16 volumio volumio[16162]: error: FusionDsp - Socket connection not established Dec 15 15:42:16 volumio volumio[16162]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 15 15:42:16 volumio volumio[16162]: info: Adding Signal Path Element [object Object] Dec 15 15:42:16 volumio volumio[16162]: info: Adding fusiondspeq DSP Signal Path Element Dec 15 15:42:16 volumio volumio[16162]: info: FusionDsp - ---- installed callbackRead Dec 15 15:42:16 volumio volumio[16162]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 15 15:42:16 volumio sudo[16403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 15 15:42:16 volumio sudo[16403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 15 15:42:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:17 volumio go-librespot[16407]: go-librespot daemon starting... Dec 15 15:42:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:17 volumio sudo[16403]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:17 volumio go-librespot[16408]: time="2025-12-15T15:42:17+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:17 volumio go-librespot[16408]: time="2025-12-15T15:42:17+01:00" level=debug msg="app state loaded" Dec 15 15:42:17 volumio go-librespot[16408]: time="2025-12-15T15:42:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:17 volumio volumio[16162]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:42:17 volumio volumio[16162]: SPOTIFY: BQCdWOnqeOuK_rR9L-6D6BQljnQ-su2MbKTG6FtFfxhV1kz65cACm9df52hSBRc8JJ0tFds1cHSeWiSL9l2oBJo_p8rMMBFYf9roqLwCr-UTJB3sLqmNoy8gjBLVEqmkfD3nmXXuhjODYdj-BkHbtbJ8OPSp793DNOFNO41dBcO8ik9A1xwmPs6v1T0TfRq09EjVkHAGHvEqLfZuTJZxXwx6_IJoehFOtiQHKl7CNTUZZEDzsxszS44mfjPz_erzJo1nMXXzIyo6IHZ3qlAbOu7WkpgZnGvy0PCaX5btem5veRDIVM1A2vPf9P6G Dec 15 15:42:17 volumio volumio[16162]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:42:17 volumio volumio[16162]: info: New Spotify access token = BQCdWOnqeOuK_rR9L-6D6BQljnQ-su2MbKTG6FtFfxhV1kz65cACm9df52hSBRc8JJ0tFds1cHSeWiSL9l2oBJo_p8rMMBFYf9roqLwCr-UTJB3sLqmNoy8gjBLVEqmkfD3nmXXuhjODYdj-BkHbtbJ8OPSp793DNOFNO41dBcO8ik9A1xwmPs6v1T0TfRq09EjVkHAGHvEqLfZuTJZxXwx6_IJoehFOtiQHKl7CNTUZZEDzsxszS44mfjPz_erzJo1nMXXzIyo6IHZ3qlAbOu7WkpgZnGvy0PCaX5btem5veRDIVM1A2vPf9P6G Dec 15 15:42:17 volumio volumio[16162]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 15:42:17 volumio volumio[16162]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 15 15:42:17 volumio volumio[16162]: info: Spotify config file written Dec 15 15:42:17 volumio sudo[16416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 15:42:17 volumio sudo[16416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:17 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 15 15:42:17 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 15 15:42:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:17 volumio mpd[16344]: 2025-12-15T15:42:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 15:42:17 volumio volumio[16162]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:17 volumio volumio[16162]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:17 volumio go-librespot[16421]: go-librespot daemon starting... Dec 15 15:42:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 15:42:17 volumio sudo[16304]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:17 volumio sudo[16314]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:17 volumio sudo[16416]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:17 volumio go-librespot[16424]: time="2025-12-15T15:42:17+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:17 volumio go-librespot[16424]: time="2025-12-15T15:42:17+01:00" level=debug msg="app state loaded" Dec 15 15:42:17 volumio go-librespot[16424]: time="2025-12-15T15:42:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:17 volumio volumio-remote-updater[682]: [2025-12-15 15:42:17] [connect] Successful connection Dec 15 15:42:17 volumio go-librespot[16424]: time="2025-12-15T15:42:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:42:17 volumio go-librespot[16424]: time="2025-12-15T15:42:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:42:17 volumio go-librespot[16424]: time="2025-12-15T15:42:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=info msg="zeroconf server listening on port 44969" Dec 15 15:42:18 volumio volumio[16162]: error: MPD error: The expression evaluated to a falsy value: Dec 15 15:42:18 volumio volumio[16162]: assert.ok(self.idling) Dec 15 15:42:18 volumio volumio[16162]: error: The expression evaluated to a falsy value: Dec 15 15:42:18 volumio volumio[16162]: assert.ok(self.idling) Dec 15 15:42:18 volumio volumio[16162]: error: updateQueue error: null Dec 15 15:42:18 volumio volumio[16162]: info: MPD running with PID16344 Dec 15 15:42:18 volumio volumio[16162]: ,establishing connection Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: No need to fix Spotify hosts Dec 15 15:42:18 volumio volumio-remote-updater[682]: [2025-12-15 15:42:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765809737 101 Dec 15 15:42:18 volumio volumio[16162]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:18 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=debug msg="obtained new client token: AACRqQghcGtcX4uynnhOFz0ay8H2LbPj7tA+Hhl/OjZxjLZ2NTQ7qFdVxuMRm/06JlYJEKerns8mjGBKm1IfIAUoPSzqZ6t78Goa000upRElQ6IL19J5ozfGKSUOYiqvTKJ28tUelKN7cSjbey6yKE68ra8oY1Mfk/9pcuD8bDMKhEcaTk1K+YkjUnXfYyHEpndpJQaVDFEKUhBuS8VeR9VymxVqsugwEmDu61A0/xwi26PkRaNlgs0=" Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=debug msg="completed challenge" Dec 15 15:42:18 volumio volumio[16162]: SPOTIFY: User informations: {"country":"NO","display_name":"Henrik Riekeles Vik","email":"hvik71@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31lljpk6idppc2yi7ezmdljotcma"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/31lljpk6idppc2yi7ezmdljotcma","id":"31lljpk6idppc2yi7ezmdljotcma","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85f5a90e783654ba229a9ad192","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82f5a90e783654ba229a9ad192","width":64}],"product":"premium","type":"user","uri":"spotify:user:31lljpk6idppc2yi7ezmdljotcma"} Dec 15 15:42:18 volumio volumio[16162]: info: Spotify Successfully logged in Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:18 volumio volumio[16162]: info: [1765809738353] CoreMusicLibrary::Adding element Spotify Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:18 volumio volumio[16162]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:18 volumio volumio[16162]: Cannot find translation for source Mixcloud Dec 15 15:42:18 volumio volumio[16162]: Cannot find translation for source YouTube Music Dec 15 15:42:18 volumio volumio[16162]: Cannot find translation for source Spotify Dec 15 15:42:18 volumio volumio[16162]: error: updateQueue error: null Dec 15 15:42:18 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:18 volumio go-librespot[16424]: time="2025-12-15T15:42:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:18 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:18 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:18 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:19 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:19 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:19 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:19 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:19 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:19 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:20 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:20 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:20 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:20 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:20 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:20 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 15 15:42:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:21 volumio go-librespot[16452]: go-librespot daemon starting... Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:21 volumio go-librespot[16453]: time="2025-12-15T15:42:21+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:21 volumio go-librespot[16453]: time="2025-12-15T15:42:21+01:00" level=debug msg="app state loaded" Dec 15 15:42:21 volumio go-librespot[16453]: time="2025-12-15T15:42:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:21 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:22 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=info msg="zeroconf server listening on port 39735" Dec 15 15:42:22 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=debug msg="obtained new client token: AADCmz+/iwM6EcHyXmuMrWGKvm2CYJ9FC5Z2vzKbMlOLvoqYkUFNGwsgpt9UkUQSOZUi01JVCw/9Slf/PnybiEHGs4TVBwOdL3voQ7WQSVnj7sHtpEMRebwxQM2PR8Y3TqrFOk/5Hl82FW4T6uscsT2fKMYXJw638PRuTTtB/D73wBPhI4QXdrk1fagY1J4f7d52xZJbROR/hGI+ax8mq+ft7yXJ2CdG4N+WAxJVAQkvdxbnkqkcUEY=" Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:22 volumio volumio[16162]: info: go-librespot daemon successfully initialized Dec 15 15:42:22 volumio go-librespot[16453]: time="2025-12-15T15:42:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:42:22 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:22 volumio sudo[16461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:42:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:22 volumio sudo[16461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:22 volumio sudo[16463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:42:22 volumio sudo[16463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:22 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:42:22 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:42:22 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:42:22 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:22 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:42:22 volumio sudo[16461]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:22 volumio sudo[16463]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:22 volumio mpd_monitor.sh[16466]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:42:22 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:22 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:23 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:24 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:25 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 15 15:42:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:25 volumio go-librespot[16484]: go-librespot daemon starting... Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=debug msg="app state loaded" Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:25 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:25 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:25 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:42:25 volumio go-librespot[16485]: time="2025-12-15T15:42:25+01:00" level=info msg="zeroconf server listening on port 36363" Dec 15 15:42:25 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio go-librespot[16485]: time="2025-12-15T15:42:26+01:00" level=debug msg="obtained new client token: AACj/Pu4A9erLHPRuO5q+6tmZ1N22kemoynwRGdgcsfk9HD2/VTqNBf+5CnV8JIoMmoUQF/9abobJJ86dxywnWsN4USW7Kbc59TQDkED+KNyFCijz2IgtuHJ84vWWaB3OZ5hRJox0T7Jxz/wcjOiIaSSjdgoz4IuwNuex1e2DpScQeEwupNGqGqno7YfsHJSrtQMGT8cQDoZd3Okun0renuqyzVNTaw1Dpt6/n3kBQ6Iro2qAwCm" Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio go-librespot[16485]: time="2025-12-15T15:42:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:26 volumio go-librespot[16485]: time="2025-12-15T15:42:26+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:26 volumio go-librespot[16485]: time="2025-12-15T15:42:26+01:00" level=debug msg="completed challenge" Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio go-librespot[16485]: time="2025-12-15T15:42:26+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio go-librespot[16485]: time="2025-12-15T15:42:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:26 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:27 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:28 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:28 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:28 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:28 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:28 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:28 volumio volumio[16162]: info: Initializing connection to go-librespot Websocket Dec 15 15:42:28 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 15 15:42:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:29 volumio go-librespot[16492]: go-librespot daemon starting... Dec 15 15:42:29 volumio go-librespot[16493]: time="2025-12-15T15:42:29+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:29 volumio go-librespot[16493]: time="2025-12-15T15:42:29+01:00" level=debug msg="app state loaded" Dec 15 15:42:29 volumio go-librespot[16493]: time="2025-12-15T15:42:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:29 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=info msg="zeroconf server listening on port 42075" Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=debug msg="obtained new client token: AACH9zIU8FkEBXcfwpKKC2ZGFGXueqi8hcBdeKcbgnXYuOYWF+V47Y3iU85RDhh9CGOgZCT61qiWoaUTy86k0+dCJ61GoYcvwg/eovsBUbxrVKkyhYL9fqy3i9et5zbDafpbJood/GHRQg7L8S9mocP5KnmUccQvOEBPfuOn4ukz9p62zcky7yaIgnRVYf6HzKwtSfXgawg7K6Se3JZfXgYiSb3AX0nT9Zq0dHC2sQuXF1Y1SlCsb4E=" Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:30 volumio go-librespot[16493]: time="2025-12-15T15:42:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:42:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:30 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:31 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:31 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:31 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:31 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:32 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:33 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:33 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 15 15:42:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:33 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:33 volumio go-librespot[16502]: go-librespot daemon starting... Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=debug msg="app state loaded" Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:33 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:33 volumio go-librespot[16503]: time="2025-12-15T15:42:33+01:00" level=info msg="zeroconf server listening on port 38085" Dec 15 15:42:34 volumio go-librespot[16503]: time="2025-12-15T15:42:34+01:00" level=debug msg="obtained new client token: AABZ5Ys4yVZEuX72huoiGJgXOjA4nNjIdJFjTEbfdEOARE7vXWqkz2b3NOx3t0XpKT853iCNgDM4vAzrj27eTcjK2zdZhuvZ03yhone/RDm5tASE6+P59OK22UfYAYKlUTqMno0a/2nQgy8rqVKBkVSMutR+gGD0bNFh7irfOtTKZwE9Wt+dL1JV44yKRgfMEcZqOvqNLJZY6193q4Hi/PHd7sj4KKTbzLf8coqR4rLEeUvV3XG5+J0=" Dec 15 15:42:34 volumio go-librespot[16503]: time="2025-12-15T15:42:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:34 volumio go-librespot[16503]: time="2025-12-15T15:42:34+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:34 volumio go-librespot[16503]: time="2025-12-15T15:42:34+01:00" level=debug msg="completed challenge" Dec 15 15:42:34 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:34 volumio go-librespot[16503]: time="2025-12-15T15:42:34+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:34 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:34 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:34 volumio go-librespot[16503]: time="2025-12-15T15:42:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:34 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:34 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:35 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:35 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:35 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:35 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:36 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:36 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:36 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:36 volumio volumio[16162]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 15 15:42:36 volumio volumio[16162]: info: Successfully started MPD Monitor Dec 15 15:42:36 volumio volumio[16162]: info: Successfully started MPD Monitor Dec 15 15:42:36 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:36 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:36 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:37 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:37 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 15 15:42:37 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:37 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:37 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:37 volumio go-librespot[16524]: go-librespot daemon starting... Dec 15 15:42:37 volumio go-librespot[16525]: time="2025-12-15T15:42:37+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:37 volumio go-librespot[16525]: time="2025-12-15T15:42:37+01:00" level=debug msg="app state loaded" Dec 15 15:42:37 volumio go-librespot[16525]: time="2025-12-15T15:42:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:37 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:38 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=info msg="zeroconf server listening on port 42331" Dec 15 15:42:38 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=debug msg="obtained new client token: AADKhhx5+kGefxcRLlfiuxFptdoV2AKl2/z2JKOdQEIv4IweeTUGX6rQCLaV23shN5wyVoyLFAxoYUKWvG/bbtjiU0rlPUzoTGmjtTwyTmfyuwcux94vHjtESa+PuEX07mXdxmAVKSDei9q55JUGvGAPSd86+Fb4BiezAW1K+GHGrFviC5rSE4W0Jr3lLxojElikwBCsavOuy2nciPFatuDiNOtoFPIWXNtzwXtHjSx1B3cHouxhvUofgw==" Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:38 volumio go-librespot[16525]: time="2025-12-15T15:42:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:42:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:38 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:39 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:39 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:40 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 15 15:42:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:41 volumio go-librespot[16532]: go-librespot daemon starting... Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=debug msg="app state loaded" Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:41 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:41 volumio go-librespot[16533]: time="2025-12-15T15:42:41+01:00" level=info msg="zeroconf server listening on port 46247" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=debug msg="obtained new client token: AAABR1rhQsVMrU/jzfhTIX8dh0W+6LeAWoTeM+KoB+4gYEdm/o96cBWhXWdu3VgPDVRwFQx5Rk9AJGQq6GJF8JOeH4fnAZwdITu4Ie0kjAJUwGHO9b34yKVMgQKT4YYIc3Uevnm6hDDBCUtQ9vXcWQalhJgD5+eZXjcOweE97r/qRapvSFeclD6jN2dWFmrn/vmrZS3oLKyQe2wrB79PhAwoznqm89fxtNZTIx4jPcXUUmj5cYMrFYA=" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=debug msg="completed challenge" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:42 volumio go-librespot[16533]: time="2025-12-15T15:42:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:43 volumio volumio[16162]: error: FusionDsp - failed to read local file: Error: ENOENT: no such file or directory, scandir '/data/INTERNAL/FusionDsp/presets//EQ3' Dec 15 15:42:45 volumio volumio[16162]: <--- Last few GCs ---> Dec 15 15:42:45 volumio volumio[16162]: [16162:0x66166e0] 43320 ms: Mark-Compact 208.9 (222.6) -> 207.7 (223.3) MB, 1288.12 / 0.00 ms (average mu = 0.142, current mu = 0.070) allocation failure; scavenge might not succeed Dec 15 15:42:45 volumio volumio[16162]: [16162:0x66166e0] 44621 ms: Mark-Compact 210.6 (224.1) -> 208.6 (224.3) MB, 1204.92 / 0.00 ms (average mu = 0.106, current mu = 0.074) allocation failure; scavenge might not succeed Dec 15 15:42:45 volumio volumio[16162]: <--- JS stacktrace ---> Dec 15 15:42:45 volumio volumio[16162]: FATAL ERROR: Reached heap limit Allocation failed - JavaScript heap out of memory Dec 15 15:42:45 volumio volumio-remote-updater[682]: [2025-12-15 15:42:45] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Dec 15 15:42:45 volumio volumio-remote-updater[682]: [2025-12-15 15:42:45] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Dec 15 15:42:45 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Dec 15 15:42:45 volumio systemd[1]: volumio.service: Failed with result 'signal'. Dec 15 15:42:45 volumio systemd[1]: volumio.service: Consumed 1min 32.706s CPU time. Dec 15 15:42:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:42:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:42:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 315. Dec 15 15:42:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 15 15:42:45 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 15 15:42:45 volumio systemd[1]: volumio.service: Consumed 1min 32.706s CPU time. Dec 15 15:42:45 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 15 15:42:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 15 15:42:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 15 15:42:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:45 volumio go-librespot[16580]: go-librespot daemon starting... Dec 15 15:42:45 volumio go-librespot[16581]: time="2025-12-15T15:42:45+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:45 volumio go-librespot[16581]: time="2025-12-15T15:42:45+01:00" level=debug msg="app state loaded" Dec 15 15:42:45 volumio go-librespot[16581]: time="2025-12-15T15:42:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=info msg="zeroconf server listening on port 33199" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=debug msg="obtained new client token: AAD8NrXzJdLRREXy6krSgiCwiGgoUnQc0Bksv2n/NAynQ15kf4GKEz4iD1jFbA7EB8t3oa/SPr1IiINjw68mXbPVoBRHLIhB2GngCMZXkpxBJwE2OIAtJN5UzaiZQA8F3KbD2+QspoqnN+7L922sF5WZSeiIziRstmdXTxqsN85gmKM0mseP79oeyk4oV13r7slkztahQkM4rwV8EgSBNmQ3A3BK71hu9dF0ZIEvc1piszqSHqjoEAo=" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:46 volumio go-librespot[16581]: time="2025-12-15T15:42:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:42:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:47 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:42:47 volumio volumio[16565]: info: ----- Volumio3 ---- Dec 15 15:42:47 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:42:47 volumio volumio[16565]: info: ----- System startup ---- Dec 15 15:42:47 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:42:48 volumio volumio[16565]: info: MYVOLUMIO Environment detected Dec 15 15:42:48 volumio volumio[16565]: info: Plugin folders cleanup Dec 15 15:42:48 volumio volumio[16565]: info: Scanning into folder /volumio/app/plugins/ Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category audio_interface Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category miscellanea Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category music_service Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category plugins.json Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category system_controller Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category user_interface Dec 15 15:42:48 volumio volumio[16565]: info: Scanning into folder /data/plugins/ Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category audio_interface Dec 15 15:42:48 volumio volumio[16565]: info: Scanning category music_service Dec 15 15:42:48 volumio volumio[16565]: info: Plugin folders cleanup completed Dec 15 15:42:48 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:42:48 volumio volumio[16565]: info: ----- Core plugins startup ---- Dec 15 15:42:48 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:42:48 volumio volumio[16565]: info: Loading plugins from folder /volumio/app/plugins/ Dec 15 15:42:48 volumio volumio[16565]: info: Adding plugin upnp to MyMusic Plugins Dec 15 15:42:48 volumio volumio[16565]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 15 15:42:48 volumio volumio[16565]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 15 15:42:48 volumio volumio[16565]: info: Loading plugins from folder /data/plugins/ Dec 15 15:42:48 volumio volumio[16565]: info: Loading plugin "system"... Dec 15 15:42:48 volumio volumio[16565]: info: Loading plugin "appearance"... Dec 15 15:42:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 15 15:42:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:49 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:49 volumio go-librespot[16600]: go-librespot daemon starting... Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=debug msg="app state loaded" Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "network"... Dec 15 15:42:49 volumio volumio[16565]: info: Refreshing Cached IP Addresses Dec 15 15:42:49 volumio sudo[16608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:42:49 volumio sudo[16608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:49 volumio sudo[16610]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:42:49 volumio sudo[16610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:49 volumio sudo[16608]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:49 volumio sudo[16610]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "services"... Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "alsa_controller"... Dec 15 15:42:49 volumio sudo[16619]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 15 15:42:49 volumio sudo[16619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:49 volumio volumio[16565]: warn: Unable to locate the audio output device Fosi Audio ZD3. Please configure a valid output device. Dec 15 15:42:49 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "wizard"... Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "networkfs"... Dec 15 15:42:49 volumio volumio[16565]: info: Starting Udev Watcher for removable devices Dec 15 15:42:49 volumio volumio[16565]: info: Ignoring mount for partition: boot Dec 15 15:42:49 volumio volumio[16565]: info: Ignoring mount for partition: volumio Dec 15 15:42:49 volumio volumio[16565]: info: Ignoring mount for partition: volumio_data Dec 15 15:42:49 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "volumio_command_line_client"... Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "upnp"... Dec 15 15:42:49 volumio volumio[16565]: info: [1765809769824] Starting Upmpd Daemon Dec 15 15:42:49 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "my_music"... Dec 15 15:42:49 volumio volumio[16565]: info: Loading plugin "mpd"... Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:49 volumio go-librespot[16601]: time="2025-12-15T15:42:49+01:00" level=info msg="zeroconf server listening on port 32843" Dec 15 15:42:50 volumio go-librespot[16601]: time="2025-12-15T15:42:50+01:00" level=debug msg="obtained new client token: AAAVuRiIBY5I7k1EG63Rb+hqatISC9TncxQAzWA1kvd6YJ6C4dcLtKh4U4PIQG9dnh+oiGClpCrU9V/TcLLq1fL0di9OvKjWO2YdfWg820UySrBY/ZwoInVky1F07toZZJIoSqtY24l0xE/EQH44cCJCqXVYME4j7tycH9Dga0d8JClB+Gf+QUxW1Mk5rhD7t8dRGY/otvvZZD0wdKOXtn6cZczsIcYRYh4acwILSt2HONiLdAJQPLg=" Dec 15 15:42:50 volumio go-librespot[16601]: time="2025-12-15T15:42:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:50 volumio go-librespot[16601]: time="2025-12-15T15:42:50+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:50 volumio go-librespot[16601]: time="2025-12-15T15:42:50+01:00" level=debug msg="completed challenge" Dec 15 15:42:50 volumio volumio[16565]: info: Loading plugin "upnp_browser"... Dec 15 15:42:50 volumio go-librespot[16601]: time="2025-12-15T15:42:50+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:50 volumio volumio-remote-updater[682]: [2025-12-15 15:42:50] [connect] Successful connection Dec 15 15:42:50 volumio go-librespot[16601]: time="2025-12-15T15:42:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:51 volumio volumio[16565]: info: Starting UPNP Browser Dec 15 15:42:51 volumio volumio[16565]: info: Loading plugin "alarm-clock"... Dec 15 15:42:51 volumio volumio[16565]: info: Loading plugin "airplay_emulation"... Dec 15 15:42:51 volumio volumio[16565]: info: Starting Shairport Sync Dec 15 15:42:51 volumio volumio[16565]: info: Loading plugin "last_100"... Dec 15 15:42:51 volumio volumio[16565]: info: Loading plugin "webradio"... Dec 15 15:42:52 volumio volumio[16565]: info: Loading plugin "i2s_dacs"... Dec 15 15:42:52 volumio volumio[16565]: info: Loading plugin "volumiodiscovery"... Dec 15 15:42:52 volumio volumio[16565]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:52 volumio volumio[16565]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:52 volumio volumio[16565]: *** WARNING *** For more information see Dec 15 15:42:52 volumio volumio[16565]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:52 volumio volumio[16565]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:52 volumio volumio[16565]: *** WARNING *** For more information see Dec 15 15:42:52 volumio node[16565]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:52 volumio node[16565]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:52 volumio node[16565]: *** WARNING *** For more information see Dec 15 15:42:52 volumio node[16565]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 15 15:42:52 volumio node[16565]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 15 15:42:52 volumio node[16565]: *** WARNING *** For more information see Dec 15 15:42:52 volumio volumio[16565]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 15 15:42:52 volumio volumio[16565]: info: Discovery: Started advertising with name: Volumio Dec 15 15:42:52 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 15 15:42:52 volumio volumio[16565]: info: Loading plugin "mixcloud"... Dec 15 15:42:52 volumio sudo[16619]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:52 volumio volumio[16565]: info: Loading plugin "spop"... Dec 15 15:42:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 15 15:42:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:53 volumio volumio[16565]: info: Loading plugin "ytcr"... Dec 15 15:42:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:53 volumio go-librespot[16644]: go-librespot daemon starting... Dec 15 15:42:53 volumio go-librespot[16645]: time="2025-12-15T15:42:53+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:53 volumio go-librespot[16645]: time="2025-12-15T15:42:53+01:00" level=debug msg="app state loaded" Dec 15 15:42:53 volumio go-librespot[16645]: time="2025-12-15T15:42:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=info msg="zeroconf server listening on port 41455" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="obtained new client token: AAB/bvdMjWWzXbKNmiI2vT2t/fcTKDYYip8Y6gJf91QZN5XBtthVzYcpHrZuyq6+X+82GVUmIslJKLRU09QJbQNz2//e6XMcxAMfvDkdKOZV5Hha+9hymWN8UDKOw66An1DWbqLAIFdKOpZQ0mcewjIKTZzgvmYAPlj+cJZ/+rF+Qku/Dj6VNbaW8uqAgehHKLlAsvZdh1u5GKlJOFDnI/F7xgW7DOWplKkr5Qtmfo9M1C7BfJ+2iRI=" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="completed keyexchange" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=debug msg="completed challenge" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:42:54 volumio go-librespot[16645]: time="2025-12-15T15:42:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:42:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:55 volumio volumio[16565]: info: Loading plugin "ytmusic"... Dec 15 15:42:55 volumio volumio[16565]: info: Loading plugin "outputs"... Dec 15 15:42:55 volumio volumio[16565]: info: Loading plugin "albumart"... Dec 15 15:42:55 volumio volumio[16565]: info: Plugin example_plugin is not enabled Dec 15 15:42:55 volumio volumio[16565]: info: Loading plugin "inputs"... Dec 15 15:42:55 volumio volumio[16565]: info: Loading plugin "updater_comm"... Dec 15 15:42:55 volumio volumio[16565]: info: Plugin mpdemulation is not enabled Dec 15 15:42:55 volumio volumio[16565]: info: Loading plugin "rest_api"... Dec 15 15:42:56 volumio volumio[16565]: info: Loading plugin "websocket"... Dec 15 15:42:56 volumio volumio[16565]: info: Starting Socket.io Server version 1.7.4 Dec 15 15:42:56 volumio volumio[16565]: info: Loading plugin "fusiondsp"... Dec 15 15:42:56 volumio volumio[16565]: info: Applying required configuration parameters for plugin fusiondsp Dec 15 15:42:56 volumio volumio[16652]: Forking 3 albumart workers Dec 15 15:42:56 volumio volumio[16565]: info: Loading i18n strings for locale no Dec 15 15:42:56 volumio volumio[16565]: Updating browse sources language Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::initPlayerControls Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: Express server listening on port 3000 Dec 15 15:42:56 volumio volumio[16565]: [Metrics] WebUI: 9s 656.85ms Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::resetVolumioState Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::getcurrentVolume Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::pushState Dec 15 15:42:56 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::volumioPushState Dec 15 15:42:56 volumio volumio[16565]: info: Volumio Network Manager: Network status updated: 1 Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:56 volumio volumio[16565]: info: Reloading queue from file Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::setRepeat null single undefined Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::pushState Dec 15 15:42:56 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::volumioPushState Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::setRandom null Dec 15 15:42:56 volumio volumio[16565]: info: CoreStateMachine::pushState Dec 15 15:42:56 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:56 volumio volumio[16565]: info: CoreCommandRouter::volumioPushState Dec 15 15:42:56 volumio volumio[16565]: info: Setting Device type: Raspberry PI Dec 15 15:42:57 volumio volumio[16565]: info: Completed loading Core Plugins Dec 15 15:42:57 volumio volumio[16565]: info: Preparing to generate the ALSA configuration file Dec 15 15:42:57 volumio volumio[16565]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 15 15:42:57 volumio volumio[16565]: info: Reading ALSA contributions from plugins. Dec 15 15:42:57 volumio volumio[16565]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:42:57 volumio volumio[16565]: info: Output device has changed, restarting MPD Dec 15 15:42:57 volumio volumio[16565]: info: Output device has changed, restarting Shairport Sync Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:57 volumio sudo[16702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:42:57 volumio sudo[16702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:57 volumio sudo[16702]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:57 volumio sudo[16704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:42:57 volumio sudo[16704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:57 volumio volumio[16565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:42:57 volumio volumio[16565]: info: ___________ START PLUGINS ___________ Dec 15 15:42:57 volumio volumio[16565]: info: ControllerMpd::onStart: Initializing MPD Dec 15 15:42:57 volumio volumio[16565]: info: Creating MPD Configuration file Dec 15 15:42:57 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:57 volumio volumio[16565]: info: [1765809777327] CoreMusicLibrary::Adding element Media Servers Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:57 volumio sudo[16714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:42:57 volumio sudo[16713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:42:57 volumio sudo[16713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:57 volumio volumio[16565]: info: UPNP Browser: Client initialized successfully Dec 15 15:42:57 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:42:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:42:57 volumio systemd[1]: mpd.service: Consumed 4.073s CPU time. Dec 15 15:42:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:42:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:42:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:42:57 volumio sudo[16713]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:57 volumio sudo[16714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:42:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:42:57 volumio volumio[16565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:57 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:42:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:42:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:42:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:42:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:42:57 volumio volumio[16565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:42:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:57 volumio volumio[16565]: info: [1765809777562] CoreMusicLibrary::Adding element Last_100 Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:57 volumio volumio[16565]: info: [1765809777585] CoreMusicLibrary::Adding element Webradio Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:42:57 volumio volumio[16565]: info: Initializing BBC Radios Dec 15 15:42:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 15 15:42:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:57 volumio volumio[16565]: info: [1765809777793] CoreMusicLibrary::Adding element Mixcloud Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:57 volumio volumio[16565]: Cannot find translation for source Mixcloud Dec 15 15:42:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:57 volumio go-librespot[16740]: go-librespot daemon starting... Dec 15 15:42:57 volumio volumio[16565]: info: Creating Spotify config file Dec 15 15:42:57 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:57 volumio sudo[16728]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:42:57 volumio sudo[16728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:42:57 volumio go-librespot[16743]: time="2025-12-15T15:42:57+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:57 volumio go-librespot[16743]: time="2025-12-15T15:42:57+01:00" level=debug msg="app state loaded" Dec 15 15:42:57 volumio go-librespot[16743]: time="2025-12-15T15:42:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:57 volumio sudo[16728]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:58 volumio volumio[16663]: Starting albumart workers Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=info msg="zeroconf server listening on port 41897" Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=debug msg="obtained new client token: AACgg2Pe7lTqJZDRbynF7GPPaxlkjijrCgS5y7M83OVOUtViN+qcrHyjG0gqV95ieUBRGFcnWRaLIxfyRTvbiTKwXEyBv5YDENfdos5xy58maPnVpFln/CNaMnP6ENV9EQYUayFxjaR5LhYZWOzb8lr5IuxKPzvrkoyrTeG9R95/mvENODQ2hjochto8w78gI2R0tFxehTwSMf6lXjkC/pwWNRKHI0ipE8zw8lKUzc8ZQlseOcmJhhbirg==" Dec 15 15:42:58 volumio volumio[16565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:42:58 volumio volumio[16565]: info: [1765809778582] CoreMusicLibrary::Adding element YouTube Music Dec 15 15:42:58 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:42:58 volumio volumio[16565]: Cannot find translation for source Mixcloud Dec 15 15:42:58 volumio volumio[16565]: Cannot find translation for source YouTube Music Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:42:58 volumio go-librespot[16743]: time="2025-12-15T15:42:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.24:32972->34.158.1.133:4070: read: connection reset by peer" Dec 15 15:42:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:42:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:42:58 volumio volumio[16565]: info: Loading i18n strings for locale no Dec 15 15:42:58 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 15 15:42:58 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:58 volumio volumio[16565]: info: FusionDsp - mixtype--------------------- None Dec 15 15:42:58 volumio volumio[16565]: info: Volumio Calling Home Dec 15 15:42:58 volumio volumio[16662]: Starting albumart workers Dec 15 15:42:58 volumio volumio[16565]: info: Preparing to generate the ALSA configuration file Dec 15 15:42:58 volumio volumio[16664]: Starting albumart workers Dec 15 15:42:59 volumio volumio[16565]: info: Discovery: adding 8b7599b7-0a8c-410b-b912-d40cd4f4da34 Dec 15 15:42:59 volumio volumio[16565]: info: Discovery: Found device Volumio Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:59 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:59 volumio volumio[16565]: info: Discovery: this is already registered, 8b7599b7-0a8c-410b-b912-d40cd4f4da34 Dec 15 15:42:59 volumio volumio[16565]: info: Discovery: Found device Volumio Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::volumioGetState Dec 15 15:42:59 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:42:59 volumio volumio[16565]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 15 15:42:59 volumio volumio[16565]: info: Reading ALSA contributions from plugins. Dec 15 15:42:59 volumio volumio[16565]: info: MPD Permissions set Dec 15 15:42:59 volumio volumio[16565]: info: MPD Permissions set Dec 15 15:42:59 volumio volumio[16565]: info: Spotify config file written Dec 15 15:42:59 volumio sudo[16763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 15:42:59 volumio sudo[16763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:42:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:59 volumio volumio[16565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 15 15:42:59 volumio volumio[16565]: info: Volumio called home Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:42:59 volumio go-librespot[16765]: go-librespot daemon starting... Dec 15 15:42:59 volumio sudo[16763]: pam_unix(sudo:session): session closed for user root Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio go-librespot[16775]: time="2025-12-15T15:42:59+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:42:59 volumio go-librespot[16775]: time="2025-12-15T15:42:59+01:00" level=debug msg="app state loaded" Dec 15 15:42:59 volumio go-librespot[16775]: time="2025-12-15T15:42:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:42:59 volumio volumio[16565]: info: No need to fix Spotify hosts Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=info msg="zeroconf server listening on port 44787" Dec 15 15:43:00 volumio volumio[16565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=debug msg="obtained new client token: AAB0TA4huFoG1r+LE9Z22cR2xP2hEc6MGRFoZHMPreLFhrixyndmid3taUfeBaYDVTlaMwjA/Meol7NyDkYtQBrTQ8h6CxH6OmF5cPuJZwn4jXLWfahEucNjC6UNEE5C3X0tlRzG+rV/QOINSFu2RKxIxtOiYXj+xmT74TXij0fYzv2fuVQgFSqMjSwsa2mkPqWGpVTTkeDvWHMG8DMtJ32PlW4AMckqXXGABXgtzog3LkYv7KghTs51yA==" Dec 15 15:43:00 volumio sudo[16811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 15:43:00 volumio sudo[16811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:00 volumio sudo[16811]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:00 volumio sudo[16813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 15:43:00 volumio sudo[16813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:43:00 volumio sudo[16813]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:00 volumio go-librespot[16775]: time="2025-12-15T15:43:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:43:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:43:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:43:00 volumio volumio[16565]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 15 15:43:00 volumio volumio[16565]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 15 15:43:00 volumio volumio[16565]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 15 15:43:00 volumio volumio[16565]: info: camilladsp service started and running in background, instance 1 Dec 15 15:43:00 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:00 volumio volumio[16565]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for ZD3 Dec 15 15:43:00 volumio volumio[16565]: cannot open device 'volumioHw': No such device Dec 15 15:43:00 volumio volumio[16565]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 15 15:43:00 volumio volumio[16565]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for ZD3 Dec 15 15:43:00 volumio volumio[16565]: cannot open device 'volumioHw': No such device Dec 15 15:43:00 volumio volumio[16565]: info: FusionDsp loaded Dec 15 15:43:00 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 15:43:00 volumio volumio[16565]: error: FusionDsp - Socket connection not established Dec 15 15:43:00 volumio volumio[16565]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 15 15:43:00 volumio volumio[16565]: info: Adding Signal Path Element [object Object] Dec 15 15:43:00 volumio volumio[16565]: info: Adding fusiondspeq DSP Signal Path Element Dec 15 15:43:00 volumio volumio[16565]: info: FusionDsp - ---- installed callbackRead Dec 15 15:43:00 volumio volumio[16565]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 15 15:43:00 volumio sudo[16830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 15 15:43:00 volumio sudo[16830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:00 volumio volumio[16565]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:43:00 volumio volumio[16565]: SPOTIFY: BQAckRSY2QfqqyY8gb2GhASwSuMN2T6oHIPd_PNITkkF8A_zT4GEc51Qm6sJBk5OXa9unxsCuN1Zrelt4Fst7mtp08o9zBXMi3UXyvM4T-LmI3bX4RhjVk7gAIdrQqlx38DpydDt-1_5rU7f6uo5T6GTyiYFZkXvJJqmQK4DNbhJasyO4jJhrYkRpgqz3p0YMuL7-S8Q8GXXDwaCEfLxl7PIhg9aIAQdxXXMCZA9ZAVj8rDAq3TrqKks9aECFonAOIph1bc_HsfqzEDqN6AHAI01TlQzOPrZBtjZ8HcL3trm7mJpN5tkyERqDOAR Dec 15 15:43:00 volumio volumio[16565]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 15:43:00 volumio volumio[16565]: info: New Spotify access token = BQAckRSY2QfqqyY8gb2GhASwSuMN2T6oHIPd_PNITkkF8A_zT4GEc51Qm6sJBk5OXa9unxsCuN1Zrelt4Fst7mtp08o9zBXMi3UXyvM4T-LmI3bX4RhjVk7gAIdrQqlx38DpydDt-1_5rU7f6uo5T6GTyiYFZkXvJJqmQK4DNbhJasyO4jJhrYkRpgqz3p0YMuL7-S8Q8GXXDwaCEfLxl7PIhg9aIAQdxXXMCZA9ZAVj8rDAq3TrqKks9aECFonAOIph1bc_HsfqzEDqN6AHAI01TlQzOPrZBtjZ8HcL3trm7mJpN5tkyERqDOAR Dec 15 15:43:00 volumio volumio[16565]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 15:43:00 volumio sudo[16830]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:00 volumio volumio[16565]: info: Starting Shairport Sync Dec 15 15:43:00 volumio volumio[16565]: info: Starting Shairport Sync Dec 15 15:43:00 volumio volumio[16565]: info: Starting Shairport Sync Dec 15 15:43:00 volumio sudo[16833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:43:00 volumio sudo[16833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio sudo[16835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:43:01 volumio sudo[16835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 15:43:01 volumio volumio[16565]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 15 15:43:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 15:43:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:43:01 volumio systemd[1]: shairport-sync.service: Consumed 2.760s CPU time. Dec 15 15:43:01 volumio sudo[16838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:43:01 volumio sudo[16838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:43:01 volumio sudo[16833]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:01 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 15:43:01 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 15:43:01 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:43:01 volumio volumio[16565]: info: Asound.conf file unchanged, so no further update is needed Dec 15 15:43:01 volumio volumio[16565]: info: Output device has changed, restarting MPD Dec 15 15:43:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:43:01 volumio sudo[16835]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:01 volumio sudo[16838]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:01 volumio volumio[16565]: info: Output device has changed, restarting Shairport Sync Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:01 volumio sudo[16845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 15 15:43:01 volumio sudo[16845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio sudo[16847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 15 15:43:01 volumio volumio[16565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 15 15:43:01 volumio sudo[16847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio sudo[16845]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:01 volumio volumio[16565]: info: Shairport-Sync Started Dec 15 15:43:01 volumio volumio[16565]: Error adding Membership: Error: addMembership EINVAL Dec 15 15:43:01 volumio volumio[16565]: info: MPD Permissions set Dec 15 15:43:01 volumio volumio[16565]: info: Shairport-Sync Started Dec 15 15:43:01 volumio volumio[16565]: info: Shairport-Sync Started Dec 15 15:43:01 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 15 15:43:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 15 15:43:01 volumio systemd[1]: mpd.service: Consumed 3.618s CPU time. Dec 15 15:43:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 15 15:43:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 15 15:43:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 15 15:43:01 volumio sudo[16870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 15 15:43:01 volumio sudo[16870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 15 15:43:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio sudo[16870]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::volumioGetState Dec 15 15:43:01 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:43:01 volumio volumio[16565]: info: Upmpdcli Daemon Started Dec 15 15:43:01 volumio volumio[16565]: info: Starting Shairport Sync Dec 15 15:43:01 volumio sudo[16872]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 15 15:43:01 volumio sudo[16872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 15 15:43:01 volumio sudo[16872]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:01 volumio sudo[16879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 15 15:43:01 volumio sudo[16879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:01 volumio volumio[16565]: SPOTIFY: User informations: {"country":"NO","display_name":"Henrik Riekeles Vik","email":"hvik71@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31lljpk6idppc2yi7ezmdljotcma"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/31lljpk6idppc2yi7ezmdljotcma","id":"31lljpk6idppc2yi7ezmdljotcma","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85f5a90e783654ba229a9ad192","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82f5a90e783654ba229a9ad192","width":64}],"product":"premium","type":"user","uri":"spotify:user:31lljpk6idppc2yi7ezmdljotcma"} Dec 15 15:43:01 volumio volumio[16565]: info: Spotify Successfully logged in Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 15:43:01 volumio volumio[16565]: info: [1765809781914] CoreMusicLibrary::Adding element Spotify Dec 15 15:43:01 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 15:43:01 volumio volumio[16565]: Cannot find translation for source Mixcloud Dec 15 15:43:01 volumio volumio[16565]: Cannot find translation for source YouTube Music Dec 15 15:43:01 volumio volumio[16565]: Cannot find translation for source Spotify Dec 15 15:43:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 15 15:43:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 15 15:43:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:43:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 15 15:43:02 volumio sudo[16879]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:02 volumio volumio[16565]: info: Shairport-Sync Started Dec 15 15:43:02 volumio volumio[16565]: info: [yt-cast-receiver] DIAL server listening on port 8098 Dec 15 15:43:02 volumio volumio[16565]: info: CoreCommandRouter::volumioRetrievevolume Dec 15 15:43:02 volumio volumio[16565]: info: CoreCommandRouter::volumioGetState Dec 15 15:43:02 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:43:02 volumio volumio[16565]: info: CoreStateMachine::pushState Dec 15 15:43:02 volumio volumio[16565]: info: CorePlayQueue::getTrack 0 Dec 15 15:43:02 volumio volumio[16565]: info: CoreCommandRouter::volumioPushState Dec 15 15:43:02 volumio volumio[16565]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Dec 15 15:43:02 volumio volumio[16565]: (TypeError) Cannot read properties of undefined (reading 'vol') Dec 15 15:43:02 volumio volumio[16565]: TypeError: Cannot read properties of undefined (reading 'vol') Dec 15 15:43:02 volumio volumio[16565]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Dec 15 15:43:02 volumio volumio[16565]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 15 15:43:02 volumio volumio[16565]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Dec 15 15:43:02 volumio volumio[16565]: at async /data/plugins/music_service/ytcr/dist/index.js:346:13 Dec 15 15:43:02 volumio volumio-remote-updater[682]: [2025-12-15 15:43:02] [connect] Successful connection Dec 15 15:43:02 volumio volumio-remote-updater[682]: [2025-12-15 15:43:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765809782 101 Dec 15 15:43:02 volumio volumio[16565]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Dec 15 15:43:03 volumio volumio[16565]: info: go-librespot daemon successfully initialized Dec 15 15:43:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 15 15:43:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:03 volumio go-librespot[16900]: go-librespot daemon starting... Dec 15 15:43:03 volumio go-librespot[16901]: time="2025-12-15T15:43:03+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:43:03 volumio go-librespot[16901]: time="2025-12-15T15:43:03+01:00" level=debug msg="app state loaded" Dec 15 15:43:03 volumio go-librespot[16901]: time="2025-12-15T15:43:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=info msg="zeroconf server listening on port 33827" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="obtained new client token: AAAxVWVLyQeddIWop1AfIDB/D1QWCbXfOB7cl5RMz7i7TmzEymMN9xftbDG0ixVAWq5T/ZIHx3gLPFFeUNsVRPoxfO2O58j7sHPFGIzAopnvnaFipo54+4JL0QDalyiqabo8yhi4VHcngafmlZr/OZzmzRH2VkgSAeMZ6XrUghVY4Us0ioKFbADvL4tmuRHw6DejFvTyjIvhJIcqAa/Y9ar1LR1nOcuFEbG+aXnoiV94c49NPXh36Qg=" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="completed keyexchange" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=debug msg="completed challenge" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:43:04 volumio go-librespot[16901]: time="2025-12-15T15:43:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:43:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:43:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:43:05 volumio mpd[16882]: 2025-12-15T15:43:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 15 15:43:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 15 15:43:05 volumio sudo[16847]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:05 volumio sudo[16714]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:05 volumio sudo[16704]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:05 volumio volumio[16565]: error: MPD error: The expression evaluated to a falsy value: Dec 15 15:43:05 volumio volumio[16565]: assert.ok(self.idling) Dec 15 15:43:05 volumio volumio[16565]: error: The expression evaluated to a falsy value: Dec 15 15:43:05 volumio volumio[16565]: assert.ok(self.idling) Dec 15 15:43:05 volumio volumio[16565]: error: MPD error: The expression evaluated to a falsy value: Dec 15 15:43:05 volumio volumio[16565]: assert.ok(self.idling) Dec 15 15:43:05 volumio volumio[16565]: error: The expression evaluated to a falsy value: Dec 15 15:43:05 volumio volumio[16565]: assert.ok(self.idling) Dec 15 15:43:05 volumio volumio[16565]: info: MPD running with PID16882 Dec 15 15:43:05 volumio volumio[16565]: ,establishing connection Dec 15 15:43:05 volumio volumio[16565]: error: updateQueue error: null Dec 15 15:43:05 volumio volumio[16565]: info: Completed starting Core Plugins Dec 15 15:43:05 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:43:05 volumio volumio[16565]: info: ----- MyVolumio plugins startup ---- Dec 15 15:43:05 volumio volumio[16565]: info: ------------------------------------------- Dec 15 15:43:05 volumio volumio[16565]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 15 15:43:05 volumio volumio[16565]: error: updateQueue error: null Dec 15 15:43:06 volumio volumio[16565]: info: Initializing connection to go-librespot Websocket Dec 15 15:43:06 volumio volumio[16565]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:43:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 15 15:43:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:08 volumio go-librespot[16913]: go-librespot daemon starting... Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=debug msg="app state loaded" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:43:08 volumio sudo[16921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:43:08 volumio sudo[16921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:08 volumio sudo[16924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:43:08 volumio sudo[16924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:08 volumio sudo[16927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 15 15:43:08 volumio sudo[16927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:08 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 15 15:43:08 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 15 15:43:08 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 15 15:43:08 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 15 15:43:08 volumio sudo[16924]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:08 volumio sudo[16921]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:08 volumio mpd_monitor.sh[16932]: MPD Monitor Service: Starting MPD Monitor Service Dec 15 15:43:08 volumio sudo[16927]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:08 volumio volumio[16565]: info: Successfully started MPD Monitor Dec 15 15:43:08 volumio volumio[16565]: info: Successfully started MPD Monitor Dec 15 15:43:08 volumio volumio[16565]: info: Successfully started MPD Monitor Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=info msg="zeroconf server listening on port 41167" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=debug msg="obtained new client token: AAAgrrdIpPOR/VgEGESrUlL1A9C4nfPMFPp4kuLTl1cAQlRoSSMlQJxjU8tW2Qubx6d8jZlo0Ah9gGbc+yzb5yGZp9G3Pj1hDesEYyUUoiBdQm9WI3+6zUyJLdmLAWadyV6my9U47lvfBh33JGhvk0RgL3ZnPJMiQkIO2SVkSGTP10VLwq2N49OozyeY6BuLjXxWs4TjHOIeo7seVjz3EcSM90LnpRlw0iq/UYGrHy6uxHG7BuuM0hNIyQ==" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:43:08 volumio go-librespot[16914]: time="2025-12-15T15:43:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.24:38716->34.158.1.133:4070: read: connection reset by peer" Dec 15 15:43:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:43:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:43:09 volumio volumio[16565]: info: Initializing connection to go-librespot Websocket Dec 15 15:43:09 volumio volumio[16565]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:43:10 volumio volumio[16565]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 15 15:43:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 15 15:43:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:11 volumio go-librespot[16950]: go-librespot daemon starting... Dec 15 15:43:11 volumio go-librespot[16951]: time="2025-12-15T15:43:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:43:11 volumio go-librespot[16951]: time="2025-12-15T15:43:11+01:00" level=debug msg="app state loaded" Dec 15 15:43:11 volumio go-librespot[16951]: time="2025-12-15T15:43:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=info msg="zeroconf server listening on port 35507" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=debug msg="obtained new client token: AACCqHwbpDZVPBtprUy1wFMu9sDoUElkrSBPIx2pfOz//po13CrMbY6AxbobwFTBfHSGIuXQNnbOc+lvWS8SrblrFPsXPvlbdxzLTYn6slPMJdGy0JEvHFLGINmMuF5PRyOI/p3hU2X0tIdTnxN2PtuZikxFqhV55Vf785LYyIEtapMWFzF8MK21r/Nd0cC1YorsdHdKJSzp2n6T1cJwWTo5FQbgkmlr8P31dxCV8AAwEXKTqSM/FkQ=" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:43:12 volumio go-librespot[16951]: time="2025-12-15T15:43:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 15 15:43:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:43:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:43:12 volumio volumio[16565]: info: Initializing connection to go-librespot Websocket Dec 15 15:43:12 volumio volumio[16565]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin bluetooth to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin multiroom to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin metavolumio to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin cd_controller to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 15 15:43:13 volumio volumio[16565]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 15 15:43:14 volumio volumio[16565]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 15 15:43:14 volumio volumio[16565]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 15 15:43:14 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:14 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:14 volumio volumio[16565]: info: Starting MyVolumio Remote Streaming Endpoints Dec 15 15:43:14 volumio volumio[16565]: info: MyVolumio login type: Token Dec 15 15:43:14 volumio volumio[16565]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 15 15:43:14 volumio volumio[16565]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 15 15:43:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 15 15:43:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:15 volumio go-librespot[16959]: go-librespot daemon starting... Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=debug msg="app state loaded" Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 15:43:15 volumio volumio[16565]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 15 15:43:15 volumio volumio[16565]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 15 15:43:15 volumio volumio[16565]: info: Streaming services startup Dec 15 15:43:15 volumio volumio[16565]: info: Starting Streaming Daemon Dec 15 15:43:15 volumio sudo[16968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 15:43:15 volumio sudo[16968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 15:43:15 volumio volumio[16565]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 15 15:43:15 volumio go-librespot[16960]: time="2025-12-15T15:43:15+01:00" level=info msg="zeroconf server listening on port 43439" Dec 15 15:43:15 volumio sudo[16968]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:15 volumio volumio[16565]: info: Initializing connection to go-librespot Websocket Dec 15 15:43:16 volumio volumio[16565]: error: Cannot start Volumio Streaming Daemon Dec 15 15:43:16 volumio volumio[16565]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 15:43:16 volumio volumio[16565]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=debug msg="new websocket client" Dec 15 15:43:16 volumio volumio[16565]: info: Connection to go-librespot Websocket established Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=debug msg="obtained new client token: AAA/WlycUOCfwgNM1i+gXON7ID4padSgsLuJOlO4bTsy58AINP2mnQcEHkRj+Y00WrjFQ6ypK8q6lTeoMbRIPMkFcS4KTN7y18zI+4mIW+BibXjXsM0mQNwviPcxPRJ113GmjDQ9J08rSiEXyxnYe8H/yQ93ZyfKPhsFxGVI6Ba790ocUBUzsr05EAod+3/cT9GVYfN1e32npYvArWodN+juu6VWs6/poRMSMnpH2MjnvVHXi2hbyZA=" Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=debug msg="completed keyexchange" Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=debug msg="completed challenge" Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=info msg="authenticated AP" username="31************************ma" Dec 15 15:43:16 volumio volumio[16565]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 15 15:43:16 volumio go-librespot[16960]: time="2025-12-15T15:43:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 15:43:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 15:43:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 15:43:16 volumio volumio[16565]: info: Connection to go-librespot Websocket closed Dec 15 15:43:17 volumio volumio[16565]: info: MyVolumio token set successfully Dec 15 15:43:17 volumio volumio[16565]: info: MYVOLUMIO: Adding device Dec 15 15:43:17 volumio volumio[16565]: info: MYVOLUMIO: Evaluating Server Dec 15 15:43:17 volumio volumio[16565]: info: MyVolumio status changed Dec 15 15:43:17 volumio volumio[16565]: info: Streaming services startup Dec 15 15:43:17 volumio volumio[16565]: info: Starting Streaming Daemon Dec 15 15:43:17 volumio volumio[16565]: info: Removing browser output: myVolumio user plan is not superstar Dec 15 15:43:17 volumio volumio[16565]: info: Removing audio output: Dec 15 15:43:17 volumio volumio[16565]: info: Stoppping Tunnel 1 Dec 15 15:43:17 volumio sudo[16996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 15 15:43:17 volumio sudo[16996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:17 volumio sudo[16998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 15 15:43:17 volumio sudo[16998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:17 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. Dec 15 15:43:17 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. Dec 15 15:43:17 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. Dec 15 15:43:17 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. Dec 15 15:43:17 volumio sudo[16996]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:17 volumio volumio[16565]: error: Cannot start Volumio Streaming Daemon Dec 15 15:43:17 volumio volumio[16565]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 15 15:43:17 volumio volumio[16565]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 15 15:43:17 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. Dec 15 15:43:17 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. Dec 15 15:43:17 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. Dec 15 15:43:17 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. Dec 15 15:43:17 volumio sudo[16998]: pam_unix(sudo:session): session closed for user root Dec 15 15:43:17 volumio volumio[16565]: info: Remote SSH Stopped Dec 15 15:43:17 volumio volumio[16565]: info: Setting Geolocation for MyVolumio to eu11 Dec 15 15:43:17 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:17 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:17 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:18 volumio volumio[16565]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 15 15:43:18 volumio volumio[16565]: info: Updating MyVolumio device info Dec 15 15:43:18 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:18 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:18 volumio volumio[16565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 15 15:43:19 volumio volumio[16565]: info: Getting Spotify volume Dec 15 15:43:19 volumio volumio[16565]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:43:19 volumio volumio[16565]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 15:43:19 volumio volumio[16565]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 15 15:43:19 volumio volumio[16565]: errno: -111, Dec 15 15:43:19 volumio volumio[16565]: code: 'ECONNREFUSED', Dec 15 15:43:19 volumio volumio[16565]: syscall: 'connect', Dec 15 15:43:19 volumio volumio[16565]: address: '127.0.0.1', Dec 15 15:43:19 volumio volumio[16565]: port: 9879, Dec 15 15:43:19 volumio volumio[16565]: response: undefined Dec 15 15:43:19 volumio volumio[16565]: } Dec 15 15:43:19 volumio volumio[16565]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 15:43:19 volumio sudo[17016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 15:42' Dec 15 15:43:19 volumio sudo[17016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 15:43:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 15 15:43:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 15:43:20 volumio go-librespot[17032]: go-librespot daemon starting... Dec 15 15:43:20 volumio go-librespot[17033]: time="2025-12-15T15:43:20+01:00" level=info msg="running go-librespot 0.4.0" Dec 15 15:43:20 volumio go-librespot[17033]: time="2025-12-15T15:43:20+01:00" level=debug msg="app state loaded" Dec 15 15:43:20 volumio go-librespot[17033]: time="2025-12-15T15:43:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"