Sep 01 00:39:00 stefans-speaker volumio[21875]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 01 00:39:00 stefans-speaker volumio[21875]: info: Completed starting MyVolumio Plugin Sep 01 00:39:00 stefans-speaker volumio[21875]: [Metrics] CommandRouter: 44s 183.88ms Sep 01 00:39:00 stefans-speaker volumio[21875]: info: CoreCommandRouter::volumiosetStartupVolume Sep 01 00:39:00 stefans-speaker volumio[21875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:00 stefans-speaker volumio[21875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:00 stefans-speaker volumio[21875]: info: CoreCommandRouter::Close All Modals sent Sep 01 00:39:00 stefans-speaker volumio[21875]: info: CoreCommandRouter::Close All Modals sent Sep 01 00:39:01 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 00:39:01 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:01 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:01 stefans-speaker go-librespot[22347]: go-librespot daemon starting... Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=debug msg="app state loaded" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:01 stefans-speaker volumio[21875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 00:39:01 stefans-speaker volumio[21875]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 00:39:01 stefans-speaker volumio[21875]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02: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]" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02: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]" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02: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]" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=info msg="zeroconf server listening on port 40527" Sep 01 00:39:01 stefans-speaker volumio[21875]: info: Initializing connection to go-librespot Websocket Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=debug msg="new websocket client" Sep 01 00:39:01 stefans-speaker volumio[21875]: info: Connection to go-librespot Websocket established Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=debug msg="obtained new client token: AAGJ3uQUqgX52JoWochTSE/OYmt9WZut1LrbX9+V9tk5xK0Msj50evrYazYvQTp+/HHdeREQgSoQYmFUXj2+RmU8fnCx0QSxoi4ObduVguO/EiNz1YIJN2CYA8zJTE+nf7qJt+3913qB8rYTUSsmNsuXSsjRpdReowNtB8pfZ4FIvRRK6DLHVT5GDXN6JvXg31X1SsQgH4VYkWkf4so5ItjKoyg1v6wtCiU5A9oWGiyQ8USfx4ZKjpvTH1Q=" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=debug msg="completed challenge" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:01 stefans-speaker go-librespot[22348]: time="2026-09-01T00:39:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:02 stefans-speaker volumio[21875]: info: Connection to go-librespot Websocket closed Sep 01 00:39:04 stefans-speaker volumio[21875]: info: Getting Spotify volume Sep 01 00:39:04 stefans-speaker volumio[21875]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:39:04 stefans-speaker volumio[21875]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:39:04 stefans-speaker volumio[21875]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 00:39:04 stefans-speaker volumio[21875]: errno: -111, Sep 01 00:39:04 stefans-speaker volumio[21875]: code: 'ECONNREFUSED', Sep 01 00:39:04 stefans-speaker volumio[21875]: syscall: 'connect', Sep 01 00:39:04 stefans-speaker volumio[21875]: address: '127.0.0.1', Sep 01 00:39:04 stefans-speaker volumio[21875]: port: 9879, Sep 01 00:39:04 stefans-speaker volumio[21875]: response: undefined Sep 01 00:39:04 stefans-speaker volumio[21875]: } Sep 01 00:39:04 stefans-speaker volumio[21875]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:39:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 01 00:39:05 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:05 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:05 stefans-speaker go-librespot[22371]: go-librespot daemon starting... Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=debug msg="app state loaded" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02: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]" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02: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]" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02: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]" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=info msg="zeroconf server listening on port 38953" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=debug msg="obtained new client token: AAEMDTCCXF3mFr3UntEUgh8JuUhb8sHtwUjLVfdEYOQwwQFP+x2haoP2mQxmY2OxVsFrcwOQOZL2K5Qw782Ea0Nlc+L/ZDU/FIjFzt0D2MwEGCY15WsdpTm6g+NV209yi7bCVmz2ykmIMRTB4XOQ3MFfAkQelXEK/SQbO01uyTYq0jdNXaeSKPGXNaf3dCkK2Nvekeb3HJV8JlRjYumdEwcpnnOvwSBOGfQJHgl2f+rbUixK8S0S7MDoJTw=" Sep 01 00:39:05 stefans-speaker sudo[22382]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:05 stefans-speaker sudo[22382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 00:38' Sep 01 00:39:05 stefans-speaker sudo[22382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=debug msg="completed challenge" Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:05 stefans-speaker sudo[22382]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:05 stefans-speaker volumio[21875]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:39:05 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 00:39:05 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 00:39:05 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:05 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 00:39:05 stefans-speaker systemd[1]: volumio.service: Consumed 35.901s CPU time. Sep 01 00:39:05 stefans-speaker go-librespot[22372]: time="2026-09-01T00:39:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:05 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:39:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:05 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:05 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:39:06 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 251. Sep 01 00:39:06 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:39:06 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 00:39:06 stefans-speaker systemd[1]: volumio.service: Consumed 35.901s CPU time. Sep 01 00:39:06 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 00:39:06 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ----- Volumio3 ---- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ----- System startup ---- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: MYVOLUMIO Environment detected Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Plugin folders cleanup Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning into folder /volumio/app/plugins/ Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category audio_interface Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category miscellanea Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category music_service Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category plugins.json Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category system_controller Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category user_interface Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning into folder /data/plugins/ Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Scanning category music_service Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Plugin folders cleanup completed Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ----- Core plugins startup ---- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Adding plugin upnp to MyMusic Plugins Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Loading plugins from folder /data/plugins/ Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Loading plugin "system"... Sep 01 00:39:08 stefans-speaker volumio[22394]: info: Loading plugin "appearance"... Sep 01 00:39:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 01 00:39:09 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:09 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:09 stefans-speaker go-librespot[22421]: go-librespot daemon starting... Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=debug msg="app state loaded" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02: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]" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02: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]" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02: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]" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=info msg="zeroconf server listening on port 45407" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=debug msg="obtained new client token: AAFPqmgtMsEclTXRKLhUePYA+mFYOMl5/j/NAthz//RfU8Sx07n3r87zUcNsEirPwGbbJzFVCfyJVO4R4Ia4Y9Egs7YXy3nedKEWgOyiAvwcgivtScjuxCyAcHhFI5HdBq9vvphR5qR5xkrFsGWKptlWBDbsAHKPMAdimi6+CiWilGu8alk/ZtEYmkaBtpnuxTgaSlj0LIhpJRiF8KUBBF3rRpmpxYO/Q9uKfBeDsVeWvZtI6rFC9Qw+oS8=" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=debug msg="completed challenge" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:09 stefans-speaker go-librespot[22422]: time="2026-09-01T00:39:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "network"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Refreshing Cached IP Addresses Sep 01 00:39:10 stefans-speaker sudo[22431]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:10 stefans-speaker sudo[22433]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "services"... Sep 01 00:39:10 stefans-speaker sudo[22431]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "alsa_controller"... Sep 01 00:39:10 stefans-speaker sudo[22431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:10 stefans-speaker sudo[22433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:39:10 stefans-speaker sudo[22440]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:10 stefans-speaker sudo[22433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:10 stefans-speaker sudo[22431]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:10 stefans-speaker sudo[22433]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:10 stefans-speaker sudo[22440]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 00:39:10 stefans-speaker sudo[22440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:10 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "wizard"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "networkfs"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Starting Udev Watcher for removable devices Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Ignoring mount for partition: boot Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Ignoring mount for partition: volumio Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Ignoring mount for partition: volumio_data Sep 01 00:39:10 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "volumio_command_line_client"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "upnp"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: [1788215950386] Starting Upmpd Daemon Sep 01 00:39:10 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "my_music"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "mpd"... Sep 01 00:39:10 stefans-speaker volumio[22394]: info: Loading plugin "upnp_browser"... Sep 01 00:39:10 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:10] [connect] Successful connection Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Starting UPNP Browser Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "alarm-clock"... Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "airplay_emulation"... Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Starting Shairport Sync Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "last_100"... Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "webradio"... Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "i2s_dacs"... Sep 01 00:39:12 stefans-speaker volumio[22394]: info: I2S DAC not set, start Auto-detection Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "volumiodiscovery"... Sep 01 00:39:12 stefans-speaker volumio[22394]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:12 stefans-speaker volumio[22394]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:12 stefans-speaker volumio[22394]: *** WARNING *** For more information see Sep 01 00:39:12 stefans-speaker volumio[22394]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:12 stefans-speaker volumio[22394]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:12 stefans-speaker volumio[22394]: *** WARNING *** For more information see Sep 01 00:39:12 stefans-speaker node[22394]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:12 stefans-speaker node[22394]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:12 stefans-speaker node[22394]: *** WARNING *** For more information see Sep 01 00:39:12 stefans-speaker node[22394]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:12 stefans-speaker node[22394]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:12 stefans-speaker node[22394]: *** WARNING *** For more information see Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 00:39:12 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:39:12 stefans-speaker volumio[22394]: info: Loading plugin "spop"... Sep 01 00:39:13 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 01 00:39:13 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:13 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:13 stefans-speaker go-librespot[22466]: go-librespot daemon starting... Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=debug msg="app state loaded" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02: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]" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02: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]" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02: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]" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=info msg="zeroconf server listening on port 41561" Sep 01 00:39:13 stefans-speaker sudo[22440]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=debug msg="obtained new client token: AAGQdJwPWHmUeUyFaESf2KsEDA/vsgwImYLU7tFMLGR6ktELkveIb8ynlkU0bpHRQtuBCiahFDQmdFY1ZENiF2FZFN2kM7ymmsUiBJVtsijCyOCexOPZpmF1xBR54A/uKsF1ZOyQajXJNBO/DIbHPXplMOwWES8yFAq96YI82RlACJi+obMuYGsc2e2Fa0QJAnkiy9aN5gVJ9hpcJKh2iDCMxapn24Q0OupsIHDNMmSQ+4ZFxTKII94o/EM=" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02: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" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 01 00:39:13 stefans-speaker volumio[22394]: info: Loading plugin "ytcr"... Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=debug msg="completed challenge" Sep 01 00:39:13 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:13+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:14 stefans-speaker go-librespot[22470]: time="2026-09-01T00:39:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:14 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading plugin "outputs"... Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading plugin "albumart"... Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Plugin example_plugin is not enabled Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading plugin "inputs"... Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading plugin "updater_comm"... Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Plugin mpdemulation is not enabled Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading plugin "rest_api"... Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading plugin "websocket"... Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Starting Socket.io Server version 1.7.4 Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Loading i18n strings for locale nl Sep 01 00:39:15 stefans-speaker volumio[22394]: Updating browse sources language Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::initPlayerControls Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: Express server listening on port 3000 Sep 01 00:39:15 stefans-speaker volumio[22394]: [Metrics] WebUI: 8s 141.40ms Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreStateMachine::resetVolumioState Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreStateMachine::getcurrentVolume Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:39:15 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Cannot read play queue from file Sep 01 00:39:15 stefans-speaker volumio[22394]: info: Volumio Network Manager: Network status updated: 2 Sep 01 00:39:15 stefans-speaker volumio[22479]: Forking 3 albumart workers Sep 01 00:39:16 stefans-speaker volumio[22394]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::pushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::updateTrackBlock Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrackBlock Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::setRepeat null single undefined Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::pushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::setRandom null Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::pushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Setting Device type: Raspberry PI Sep 01 00:39:16 stefans-speaker volumio[22394]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreStateMachine::pushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Completed loading Core Plugins Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Preparing to generate the ALSA configuration file Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Asound.conf file unchanged, so no further update is needed Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Output device has changed, restarting MPD Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Output device has changed, restarting Shairport Sync Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:16 stefans-speaker sudo[22534]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:16 stefans-speaker sudo[22534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:39:16 stefans-speaker sudo[22535]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:16 stefans-speaker sudo[22534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: ___________ START PLUGINS ___________ Sep 01 00:39:16 stefans-speaker volumio[22394]: info: ControllerMpd::onStart: Initializing MPD Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Creating MPD Configuration file Sep 01 00:39:16 stefans-speaker sudo[22534]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:16 stefans-speaker sudo[22535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:39:16 stefans-speaker sudo[22535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:16 stefans-speaker volumio[22394]: info: [1788215956544] CoreMusicLibrary::Adding element Media Servers Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:16 stefans-speaker volumio[22394]: info: UPNP Browser: Client initialized successfully Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:16 stefans-speaker sudo[22544]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:16 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 00:39:16 stefans-speaker sudo[22545]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:39:16 stefans-speaker sudo[22544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:16 stefans-speaker sudo[22544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:16 stefans-speaker sudo[22545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:39:16 stefans-speaker sudo[22545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:16 stefans-speaker sudo[22544]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:16 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:39:16 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:39:16 stefans-speaker systemd[1]: mpd.service: Consumed 4.515s CPU time. Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:16 stefans-speaker volumio[22394]: info: [1788215956700] CoreMusicLibrary::Adding element Last_100 Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:16 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:39:16 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:39:16 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:16 stefans-speaker volumio[22394]: info: [1788215956709] CoreMusicLibrary::Adding element Webradio Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Initializing BBC Radios Sep 01 00:39:16 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:39:16 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:39:16 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:39:16 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:39:16 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:39:16 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:39:16 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:16 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:39:16 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:39:16 stefans-speaker volumio[22394]: info: Creating Spotify config file Sep 01 00:39:16 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:17 stefans-speaker sudo[22572]: root : unable to resolve host stefans-speaker: System error Sep 01 00:39:17 stefans-speaker sudo[22572]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:39:17 stefans-speaker sudo[22572]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 00:39:17 stefans-speaker sudo[22572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 00:39:17 stefans-speaker sudo[22572]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:17 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 01 00:39:17 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:17 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:17 stefans-speaker go-librespot[22576]: go-librespot daemon starting... Sep 01 00:39:17 stefans-speaker go-librespot[22577]: time="2026-09-01T00:39:17+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:17 stefans-speaker volumio[22394]: info: Volumio Calling Home Sep 01 00:39:17 stefans-speaker go-librespot[22577]: time="2026-09-01T00:39:17+02:00" level=info msg="zeroconf server listening on port 45297" Sep 01 00:39:17 stefans-speaker volumio[22496]: Starting albumart workers Sep 01 00:39:18 stefans-speaker volumio[22494]: Starting albumart workers Sep 01 00:39:18 stefans-speaker volumio[22394]: info: MPD Permissions set Sep 01 00:39:18 stefans-speaker volumio[22394]: info: MPD Permissions set Sep 01 00:39:18 stefans-speaker volumio[22394]: info: Spotify config file written Sep 01 00:39:18 stefans-speaker sudo[22590]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:18 stefans-speaker volumio[22495]: Starting albumart workers Sep 01 00:39:18 stefans-speaker sudo[22590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 00:39:18 stefans-speaker sudo[22590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:18 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 00:39:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 00:39:18 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:18 stefans-speaker volumio[22394]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 00:39:18 stefans-speaker volumio[22394]: info: Volumio called home Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:18 stefans-speaker go-librespot[22592]: go-librespot daemon starting... Sep 01 00:39:18 stefans-speaker sudo[22590]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:18 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:18+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:18 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:18+02:00" level=debug msg="app state loaded" Sep 01 00:39:18 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:18 stefans-speaker volumio[22394]: info: No need to fix Spotify hosts Sep 01 00:39:18 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:18+02: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]" Sep 01 00:39:18 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:18+02: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]" Sep 01 00:39:18 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:18+02: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]" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=info msg="zeroconf server listening on port 44945" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=debug msg="obtained new client token: AAGvh4X2mVqKJnwdNwGlCVxDaDa5mrM6p3Usgc0UWXlFdKO01dw4pPKaO25pAHlSMu6V5fM4WG+3bhtd6SP1anU5qT5X1OGgUpLJ64H728ECOM1MLQ5CwrwPOiICZ5VLb7M41NnneZZoiBiQNxCsKFVafM3zwTqqpQpVSdKN0D82pzbKwIJhufRQdbMMzEDBiYHRMvL3Q//sAgqXELsLDr7ObyKxu7mJu7kbuVr8ohRYioXY4NfoIf5x" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02: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" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=debug msg="completed challenge" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:19 stefans-speaker go-librespot[22593]: time="2026-09-01T00:39:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:19 stefans-speaker volumio[22394]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:39:19 stefans-speaker volumio[22394]: SPOTIFY: BQAm_wOzmy36ljOwog5RZkw9DXR548ODCFelwDFXFCwd7BJEDyrlb5IGzm_RZ2TSjtoy0tEbHxH-uSM4ywVV7kNetxUH0-4fWFWnfRRq_m7aBNiiEpOW4W2nYM1ySvq-51p38LjRSC-hXl7lUvUTJa9griYk7dNqFgVGSv3CvIrKtuDADZJZ3Ds64G1t9D91dIB34l7WizY4neL_GgryWhOfI7uxjejgifZxUlCL2vNeUCUpCUkGXBjAn3S5LYTT3u1aIq3dZff6raEuFJ6p5NvyDb1mLDYwUqYhsO8zInn9VHRJXPi_9vI6kd-oJPc Sep 01 00:39:19 stefans-speaker volumio[22394]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:39:19 stefans-speaker volumio[22394]: info: New Spotify access token = BQAm_wOzmy36ljOwog5RZkw9DXR548ODCFelwDFXFCwd7BJEDyrlb5IGzm_RZ2TSjtoy0tEbHxH-uSM4ywVV7kNetxUH0-4fWFWnfRRq_m7aBNiiEpOW4W2nYM1ySvq-51p38LjRSC-hXl7lUvUTJa9griYk7dNqFgVGSv3CvIrKtuDADZJZ3Ds64G1t9D91dIB34l7WizY4neL_GgryWhOfI7uxjejgifZxUlCL2vNeUCUpCUkGXBjAn3S5LYTT3u1aIq3dZff6raEuFJ6p5NvyDb1mLDYwUqYhsO8zInn9VHRJXPi_9vI6kd-oJPc Sep 01 00:39:19 stefans-speaker volumio[22394]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 00:39:19 stefans-speaker volumio[22394]: info: Starting Shairport Sync Sep 01 00:39:19 stefans-speaker volumio[22394]: info: Starting Shairport Sync Sep 01 00:39:19 stefans-speaker volumio[22394]: info: Starting Shairport Sync Sep 01 00:39:19 stefans-speaker sudo[22629]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:19 stefans-speaker sudo[22629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:39:19 stefans-speaker sudo[22629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:19 stefans-speaker sudo[22631]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:19 stefans-speaker sudo[22631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:39:19 stefans-speaker sudo[22631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:19 stefans-speaker sudo[22633]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:19 stefans-speaker sudo[22633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:39:19 stefans-speaker sudo[22633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:19 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:39:19 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:39:19 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:39:19 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.861s CPU time. Sep 01 00:39:19 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:19 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:19 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:39:19 stefans-speaker sudo[22631]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:20 stefans-speaker sudo[22633]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:20 stefans-speaker sudo[22629]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:20 stefans-speaker volumio[22394]: info: Shairport-Sync Started Sep 01 00:39:20 stefans-speaker volumio[22394]: Error adding Membership: Error: addMembership EINVAL Sep 01 00:39:20 stefans-speaker volumio[22394]: info: Shairport-Sync Started Sep 01 00:39:20 stefans-speaker volumio[22394]: info: Shairport-Sync Started Sep 01 00:39:20 stefans-speaker volumio[22394]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 00:39:20 stefans-speaker volumio[22394]: info: Spotify Successfully logged in Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:20 stefans-speaker volumio[22394]: info: [1788215960053] CoreMusicLibrary::Adding element Spotify Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:20 stefans-speaker volumio[22394]: Cannot find translation for source Spotify Sep 01 00:39:20 stefans-speaker volumio[22394]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:39:20 stefans-speaker sudo[22668]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:20 stefans-speaker sudo[22668]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:39:20 stefans-speaker sudo[22668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:20 stefans-speaker sudo[22670]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:20 stefans-speaker sudo[22668]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:20 stefans-speaker sudo[22670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:39:20 stefans-speaker sudo[22670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:20 stefans-speaker sudo[22670]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:20 stefans-speaker volumio[22394]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CoreStateMachine::pushState Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:20 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:20 stefans-speaker sudo[22675]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:20 stefans-speaker sudo[22675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 00:39:20 stefans-speaker sudo[22675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:20 stefans-speaker sudo[22675]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:20 stefans-speaker volumio[22394]: info: Upmpdcli Daemon Started Sep 01 00:39:21 stefans-speaker mpd[22575]: 2026-09-01T00:39:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 00:39:21 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 00:39:21 stefans-speaker sudo[22535]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:21 stefans-speaker sudo[22545]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:21 stefans-speaker volumio[22394]: error: MPD error: The expression evaluated to a falsy value: Sep 01 00:39:21 stefans-speaker volumio[22394]: assert.ok(self.idling) Sep 01 00:39:21 stefans-speaker volumio[22394]: error: The expression evaluated to a falsy value: Sep 01 00:39:21 stefans-speaker volumio[22394]: assert.ok(self.idling) Sep 01 00:39:21 stefans-speaker volumio[22394]: info: MPD running with PID22575 Sep 01 00:39:21 stefans-speaker volumio[22394]: ,establishing connection Sep 01 00:39:21 stefans-speaker volumio[22394]: error: updateQueue error: null Sep 01 00:39:21 stefans-speaker volumio[22394]: info: Completed starting Core Plugins Sep 01 00:39:21 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:21 stefans-speaker volumio[22394]: info: ----- MyVolumio plugins startup ---- Sep 01 00:39:21 stefans-speaker volumio[22394]: info: ------------------------------------------- Sep 01 00:39:21 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 00:39:21 stefans-speaker volumio[22394]: error: updateQueue error: null Sep 01 00:39:22 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 00:39:22 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:22 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:22 stefans-speaker go-librespot[22683]: go-librespot daemon starting... Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02:00" level=debug msg="app state loaded" Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:22 stefans-speaker volumio[22394]: info: go-librespot daemon successfully initialized Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02: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]" Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02: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]" Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02: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]" Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02:00" level=info msg="zeroconf server listening on port 46131" Sep 01 00:39:22 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:22+02:00" level=debug msg="obtained new client token: AAF2DpyMJiPVEawzGLs3KhlqBjYGpoXuuJU27c5IHR+1xGF5mzltNjFACdgqTnBavw4TfLlsrVP46bpELAAGMtdQa23P0oVY9wJaaor/SXGDhWGo501zs1FxPGrwlBztQzh7R33aTqiNiYvHazXC+OEkO18cFavSk/5s/0J9pQ9hsPjTpwK0HhsMNqjWvzc0+y+ie+y8F6IUOx4x1OCjHqlRvdPPcm2VlaNCwlU0OjX50gPHkpgJuQE8CgI=" Sep 01 00:39:23 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:23 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:23] [connect] Successful connection Sep 01 00:39:23 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788215963 101 Sep 01 00:39:23 stefans-speaker volumio[22394]: 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: 2 Sep 01 00:39:24 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:24+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:24 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:24+02:00" level=debug msg="completed challenge" Sep 01 00:39:24 stefans-speaker sudo[22694]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:24 stefans-speaker sudo[22694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:39:24 stefans-speaker sudo[22694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:24 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:24+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:24 stefans-speaker sudo[22696]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:24 stefans-speaker sudo[22696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:39:24 stefans-speaker sudo[22696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:24 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 00:39:24 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 00:39:24 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 00:39:24 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 00:39:24 stefans-speaker sudo[22696]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:24 stefans-speaker sudo[22694]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:24 stefans-speaker volumio[22394]: info: Successfully started MPD Monitor Sep 01 00:39:24 stefans-speaker mpd_monitor.sh[22699]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 00:39:24 stefans-speaker volumio[22394]: info: Successfully started MPD Monitor Sep 01 00:39:24 stefans-speaker go-librespot[22684]: time="2026-09-01T00:39:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:25 stefans-speaker volumio[22394]: info: Initializing connection to go-librespot Websocket Sep 01 00:39:25 stefans-speaker volumio[22394]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:39:26 stefans-speaker volumio[22394]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 00:39:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 00:39:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:28 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:28 stefans-speaker go-librespot[22703]: go-librespot daemon starting... Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=debug msg="app state loaded" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02: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]" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02: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]" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02: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]" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=info msg="zeroconf server listening on port 34795" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=debug msg="obtained new client token: AAFywk4eD4TPA0X3+Q0ZMTcsikNNXOmkc33R4O58gAQx3qaz+a7KBaZPDPDVDCVLibiP6BflXr/sEwfMYvI6PfVAyDa44oxhY2ktGdDNamEZHBFpwO/2PD1ab/rLo6dKOgf3yJSohch0JAa+GH4LxT0xo8KWaX1JAY3FXeN47Xtf9i5Fbmww4AxyR3SJTbqYN5nIOzEFJo6Cso0gr45I2eN0knQEMGqiMqJlL3DZE96ODQpts+vD9WCR9wU=" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=debug msg="completed challenge" Sep 01 00:39:28 stefans-speaker volumio[22394]: info: Initializing connection to go-librespot Websocket Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=debug msg="new websocket client" Sep 01 00:39:28 stefans-speaker volumio[22394]: info: Connection to go-librespot Websocket established Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:28 stefans-speaker go-librespot[22704]: time="2026-09-01T00:39:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:28 stefans-speaker volumio[22394]: info: Connection to go-librespot Websocket closed Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 00:39:30 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 00:39:31 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 00:39:31 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 00:39:31 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:31 stefans-speaker volumio[22394]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:31 stefans-speaker volumio[22394]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 00:39:31 stefans-speaker volumio[22394]: info: MyVolumio login type: Token Sep 01 00:39:31 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 00:39:31 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 00:39:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 00:39:32 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:32 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:32 stefans-speaker go-librespot[22725]: go-librespot daemon starting... Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=debug msg="app state loaded" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:32 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 00:39:32 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Streaming services startup Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Starting Streaming Daemon Sep 01 00:39:32 stefans-speaker volumio[22394]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 00:39:32 stefans-speaker sudo[22735]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:32 stefans-speaker sudo[22735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:39:32 stefans-speaker sudo[22735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02: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]" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02: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]" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02: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]" Sep 01 00:39:32 stefans-speaker sudo[22735]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=info msg="zeroconf server listening on port 45607" Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Getting Spotify volume Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Initializing connection to go-librespot Websocket Sep 01 00:39:32 stefans-speaker volumio[22394]: error: Cannot start Volumio Streaming Daemon Sep 01 00:39:32 stefans-speaker volumio[22394]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:39:32 stefans-speaker volumio[22394]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:39:32 stefans-speaker volumio[22394]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=debug msg="new websocket client" Sep 01 00:39:32 stefans-speaker volumio[22394]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Connection to go-librespot Websocket established Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=debug msg="obtained new client token: AAFVLkiWKXUdKh7ouhiiYvBSHzb7I2ocTFQhN70saZvEUgEXdSIrwEFVPzQnOmM6TfK56KCt9h52twRtg+mC6M/0jZMPxE0rKd3AxxG3S8wVt+o7HjbYSBz5cdJmbzGa/yFpe1sElmMH+VQIBhlVIXhkIMZEFVes8G42OeRbP3LuWxr1rVdyze+Ygao54sMW1gEHJuwKzDt8nn74+Y2nL7yEFWyFG/FEeXu/cfiPU0vKMB5Rp3tWjrQcUzw=" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:32 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:32 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:32 stefans-speaker volumio[22394]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Sep 01 00:39:32 stefans-speaker volumio[22394]: SPOTIFY: SPOTIFY VOLUME undefined Sep 01 00:39:32 stefans-speaker volumio[22394]: SPOTIFY: VOLUMIO VOLUME 70 Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Aligning Spotify Volume to Volumio Volume Sep 01 00:39:32 stefans-speaker volumio[22394]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:32 stefans-speaker volumio[22394]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Setting Spotify Volume from Volumio: 70 Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=debug msg="completed challenge" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:32 stefans-speaker go-librespot[22726]: time="2026-09-01T00:39:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:32 stefans-speaker volumio[22394]: info: Connection to go-librespot Websocket closed Sep 01 00:39:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:32 stefans-speaker volumio[22394]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:39:32 stefans-speaker volumio[22394]: Error: socket hang up Sep 01 00:39:32 stefans-speaker volumio[22394]: at connResetException (node:internal/errors:720:14) Sep 01 00:39:32 stefans-speaker volumio[22394]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 01 00:39:32 stefans-speaker volumio[22394]: at Socket.emit (node:events:526:35) Sep 01 00:39:32 stefans-speaker volumio[22394]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 00:39:32 stefans-speaker volumio[22394]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 01 00:39:32 stefans-speaker volumio[22394]: code: 'ECONNRESET', Sep 01 00:39:32 stefans-speaker volumio[22394]: response: undefined Sep 01 00:39:32 stefans-speaker volumio[22394]: } Sep 01 00:39:32 stefans-speaker volumio[22394]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:39:33 stefans-speaker sudo[22755]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:33 stefans-speaker sudo[22755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 00:38' Sep 01 00:39:33 stefans-speaker sudo[22755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:33 stefans-speaker sudo[22755]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:34 stefans-speaker volumio[22394]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:39:34 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 00:39:34 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 00:39:34 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:34 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 00:39:34 stefans-speaker systemd[1]: volumio.service: Consumed 31.683s CPU time. Sep 01 00:39:34 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:39:34 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:39:34 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 252. Sep 01 00:39:34 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 00:39:34 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 00:39:34 stefans-speaker systemd[1]: volumio.service: Consumed 31.683s CPU time. Sep 01 00:39:34 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 00:39:34 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 00:39:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 00:39:36 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:36 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:36 stefans-speaker go-librespot[22786]: go-librespot daemon starting... Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=debug msg="app state loaded" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02: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]" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02: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]" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02: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]" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=info msg="zeroconf server listening on port 35511" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=debug msg="obtained new client token: AAHXHbWoFsfE1pWyeWbQhLn+55xfzvQ6us0D7E2tl8HVu/lt7C8/oRzgN5/5eSj7RBkqXTlam2IC8R0DSyaChxF6R1cRwTBO6ipZcB1pVw6Y/lozyQm20fSSg7W0WNtIxOoqL/fqlnXFlGANPaxCxaul9YwzGi7FiX05fhh2iE2t0kfFx6iAAHtdU+ZoJddhwuW0Yo1NfOmYJZaDqYgryOCISuTalnxwvKkNgXit/6sJHf8sLhZjwKpYdOU=" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02: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" Sep 01 00:39:36 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:36 stefans-speaker volumio[22771]: info: ----- Volumio3 ---- Sep 01 00:39:36 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:36 stefans-speaker volumio[22771]: info: ----- System startup ---- Sep 01 00:39:36 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=debug msg="completed challenge" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:36 stefans-speaker go-librespot[22787]: time="2026-09-01T00:39:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:37 stefans-speaker volumio[22771]: info: MYVOLUMIO Environment detected Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Plugin folders cleanup Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning into folder /volumio/app/plugins/ Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category audio_interface Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category miscellanea Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category music_service Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category plugins.json Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category system_controller Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category user_interface Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning into folder /data/plugins/ Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Scanning category music_service Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Plugin folders cleanup completed Sep 01 00:39:37 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:37 stefans-speaker volumio[22771]: info: ----- Core plugins startup ---- Sep 01 00:39:37 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Adding plugin upnp to MyMusic Plugins Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Loading plugins from folder /data/plugins/ Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Loading plugin "system"... Sep 01 00:39:37 stefans-speaker volumio[22771]: info: Loading plugin "appearance"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "network"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Refreshing Cached IP Addresses Sep 01 00:39:38 stefans-speaker sudo[22809]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:38 stefans-speaker sudo[22811]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "services"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "alsa_controller"... Sep 01 00:39:38 stefans-speaker sudo[22809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:39:38 stefans-speaker sudo[22811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:39:38 stefans-speaker sudo[22811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:38 stefans-speaker sudo[22809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:38 stefans-speaker sudo[22818]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:38 stefans-speaker sudo[22811]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:38 stefans-speaker sudo[22809]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:38 stefans-speaker sudo[22818]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 00:39:38 stefans-speaker sudo[22818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:38 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "wizard"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "networkfs"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Starting Udev Watcher for removable devices Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Ignoring mount for partition: boot Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Ignoring mount for partition: volumio Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Ignoring mount for partition: volumio_data Sep 01 00:39:38 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "volumio_command_line_client"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "upnp"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: [1788215978877] Starting Upmpd Daemon Sep 01 00:39:38 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "my_music"... Sep 01 00:39:38 stefans-speaker volumio[22771]: info: Loading plugin "mpd"... Sep 01 00:39:39 stefans-speaker volumio[22771]: info: Loading plugin "upnp_browser"... Sep 01 00:39:39 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:39] [connect] Successful connection Sep 01 00:39:40 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 00:39:40 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:40 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:40 stefans-speaker go-librespot[22842]: go-librespot daemon starting... Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02:00" level=debug msg="app state loaded" Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02: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]" Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02: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]" Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02: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]" Sep 01 00:39:40 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:40+02:00" level=info msg="zeroconf server listening on port 35551" Sep 01 00:39:40 stefans-speaker volumio[22771]: info: Starting UPNP Browser Sep 01 00:39:40 stefans-speaker volumio[22771]: info: Loading plugin "alarm-clock"... Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Loading plugin "airplay_emulation"... Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Starting Shairport Sync Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Loading plugin "last_100"... Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Loading plugin "webradio"... Sep 01 00:39:41 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:41+02:00" level=debug msg="obtained new client token: AAHmyQZ6bFcavSHnQU1UwsIIQM1dI816G5u1IS64y8SM7o8CLxkHEWbMoB3qQCGNgsr/gYRUvK4pCF5eNmd8+kOlYEv9twLx5rqpJrB2EdQMrOv0Eijhwdk/MdBFP7zUpVHEQcaA0502P0rpg/fh9iifbSdIcTTeNHDQf+F7OANkmmFvWFFdmtTBo06vX5weA71igCgAr66DiJL3fhHoIwiQy0nyZlp/bVHlCebDzYAA+KVc8XL/o8pq" Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Loading plugin "i2s_dacs"... Sep 01 00:39:41 stefans-speaker volumio[22771]: info: I2S DAC not set, start Auto-detection Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Loading plugin "volumiodiscovery"... Sep 01 00:39:41 stefans-speaker volumio[22771]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:41 stefans-speaker volumio[22771]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:41 stefans-speaker volumio[22771]: *** WARNING *** For more information see Sep 01 00:39:41 stefans-speaker volumio[22771]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:41 stefans-speaker volumio[22771]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:41 stefans-speaker volumio[22771]: *** WARNING *** For more information see Sep 01 00:39:41 stefans-speaker node[22771]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:41 stefans-speaker node[22771]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:41 stefans-speaker node[22771]: *** WARNING *** For more information see Sep 01 00:39:41 stefans-speaker node[22771]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 00:39:41 stefans-speaker node[22771]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 00:39:41 stefans-speaker node[22771]: *** WARNING *** For more information see Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 00:39:41 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 00:39:41 stefans-speaker volumio[22771]: info: Loading plugin "spop"... Sep 01 00:39:41 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:41 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:41+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:41 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:41+02:00" level=debug msg="completed challenge" Sep 01 00:39:41 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:41+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:42 stefans-speaker sudo[22818]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:42 stefans-speaker go-librespot[22843]: time="2026-09-01T00:39:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:42 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:42 stefans-speaker volumio[22771]: info: Loading plugin "ytcr"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Loading plugin "outputs"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Loading plugin "albumart"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Plugin example_plugin is not enabled Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Loading plugin "inputs"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Loading plugin "updater_comm"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Plugin mpdemulation is not enabled Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Loading plugin "rest_api"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Loading plugin "websocket"... Sep 01 00:39:43 stefans-speaker volumio[22771]: info: Starting Socket.io Server version 1.7.4 Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Loading i18n strings for locale nl Sep 01 00:39:44 stefans-speaker volumio[22771]: Updating browse sources language Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::initPlayerControls Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: Express server listening on port 3000 Sep 01 00:39:44 stefans-speaker volumio[22771]: [Metrics] WebUI: 8s 177.41ms Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::resetVolumioState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::getcurrentVolume Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Cannot read play queue from file Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Volumio Network Manager: Network status updated: 2 Sep 01 00:39:44 stefans-speaker volumio[22853]: Forking 3 albumart workers Sep 01 00:39:44 stefans-speaker volumio[22771]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::pushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::updateTrackBlock Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrackBlock Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::setRepeat null single undefined Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::pushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::setRandom null Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::pushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Setting Device type: Raspberry PI Sep 01 00:39:44 stefans-speaker volumio[22771]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreStateMachine::pushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Completed loading Core Plugins Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Preparing to generate the ALSA configuration file Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Asound.conf file unchanged, so no further update is needed Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Output device has changed, restarting MPD Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Output device has changed, restarting Shairport Sync Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:44 stefans-speaker volumio[22771]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:39:44 stefans-speaker sudo[22911]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:44 stefans-speaker sudo[22912]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:44 stefans-speaker sudo[22911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:39:44 stefans-speaker volumio[22771]: info: ___________ START PLUGINS ___________ Sep 01 00:39:44 stefans-speaker sudo[22911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:45 stefans-speaker sudo[22912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:39:45 stefans-speaker sudo[22912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:45 stefans-speaker volumio[22771]: info: ControllerMpd::onStart: Initializing MPD Sep 01 00:39:45 stefans-speaker sudo[22911]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:45 stefans-speaker volumio[22771]: info: Creating MPD Configuration file Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:45 stefans-speaker volumio[22771]: info: [1788215985049] CoreMusicLibrary::Adding element Media Servers Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:45 stefans-speaker volumio[22771]: info: UPNP Browser: Client initialized successfully Sep 01 00:39:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 00:39:45 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:45 stefans-speaker sudo[22920]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:45 stefans-speaker sudo[22922]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:45 stefans-speaker sudo[22922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 00:39:45 stefans-speaker sudo[22920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 00:39:45 stefans-speaker sudo[22922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:45 stefans-speaker sudo[22920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:45 stefans-speaker sudo[22920]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:45 stefans-speaker go-librespot[22923]: go-librespot daemon starting... Sep 01 00:39:45 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:45 stefans-speaker volumio[22771]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:39:45 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=debug msg="app state loaded" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:45 stefans-speaker volumio[22771]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:45 stefans-speaker volumio[22771]: info: [1788215985236] CoreMusicLibrary::Adding element Last_100 Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:45 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:45 stefans-speaker volumio[22771]: info: [1788215985241] CoreMusicLibrary::Adding element Webradio Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:45 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 00:39:45 stefans-speaker systemd[1]: mpd.service: Consumed 4.516s CPU time. Sep 01 00:39:45 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 00:39:45 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 00:39:45 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:39:45 stefans-speaker volumio[22771]: info: Initializing BBC Radios Sep 01 00:39:45 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 00:39:45 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:45 stefans-speaker volumio[22771]: info: Creating Spotify config file Sep 01 00:39:45 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02: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]" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02: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]" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02: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]" Sep 01 00:39:45 stefans-speaker sudo[22944]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:39:45 stefans-speaker sudo[22944]: root : unable to resolve host stefans-speaker: System error Sep 01 00:39:45 stefans-speaker sudo[22944]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 00:39:45 stefans-speaker sudo[22944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=info msg="zeroconf server listening on port 46601" Sep 01 00:39:45 stefans-speaker sudo[22944]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=debug msg="obtained new client token: AAFyTc3kXuozXY+xKR1VNZIBnkz+OwZfdfImcBSRUv0wYqQ2AuuhzfNsx5G7RTAIrr7C+bvHZ1N91kn2U7Ck7DlS2o5IOW5Qc+u7Gj+gCRpAu2FxAqrB70PRPiljxIsa9nYe3YETRe5Q+kFeFpmCAJrJ8wOdlXgJvlkxBizQR3cQq0rfsPrPe3YeAQpxPgdFR9bmOqWUGbOEMUv0k9PqMYXyVHoBc5TIMHFSPcl6/MMvb/MFymGl8T9sadY=" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=debug msg="completed challenge" Sep 01 00:39:45 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:45+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:46 stefans-speaker go-librespot[22930]: time="2026-09-01T00:39:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:46 stefans-speaker volumio[22868]: Starting albumart workers Sep 01 00:39:46 stefans-speaker volumio[22771]: info: Volumio Calling Home Sep 01 00:39:46 stefans-speaker volumio[22869]: Starting albumart workers Sep 01 00:39:46 stefans-speaker volumio[22870]: Starting albumart workers Sep 01 00:39:46 stefans-speaker volumio[22771]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 00:39:46 stefans-speaker volumio[22771]: info: Discovery: Found device Stefan’s Speaker Sep 01 00:39:46 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:46 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:47 stefans-speaker volumio[22771]: info: MPD Permissions set Sep 01 00:39:47 stefans-speaker volumio[22771]: info: MPD Permissions set Sep 01 00:39:47 stefans-speaker volumio[22771]: info: Volumio called home Sep 01 00:39:47 stefans-speaker volumio[22771]: info: Spotify config file written Sep 01 00:39:47 stefans-speaker sudo[22962]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:47 stefans-speaker sudo[22962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 00:39:47 stefans-speaker volumio[22771]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 00:39:47 stefans-speaker sudo[22962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:47 stefans-speaker go-librespot[22975]: go-librespot daemon starting... Sep 01 00:39:47 stefans-speaker sudo[22962]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=debug msg="app state loaded" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 00:39:47 stefans-speaker volumio[22771]: info: No need to fix Spotify hosts Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02: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]" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02: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]" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02: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]" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=info msg="zeroconf server listening on port 35895" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=debug msg="obtained new client token: AAEtWKKZlkuw+fm3vJHyV2Ex35V31usDztKOThGaOleLwmGjtVOIpnUUgwJUgzjdfD0QmE4Tz2t5GKNuFtWZM2+ETBvslEKGH2AtWgOIIQ1MDQTuxX333iEt0M7O2XaPF4LlnQk6rQwKmEEwhnbBUOu49Hwf9RNwNDJ69l/XW100JvK4IGm3+aoGZ/IMt4lcor3J7FKQXs1VZSBrPY8QdgLteeMXcWbmramYEvuLsBu+V7rhJ9kjSAbFrBQ=" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:47 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=debug msg="completed challenge" Sep 01 00:39:48 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:47+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:48 stefans-speaker volumio[22771]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:39:48 stefans-speaker volumio[22771]: SPOTIFY: BQCO90HUaBPHZDUq8SOZezPKtsl7ze5XwfsBJttKSwrZb2YUWqaLeYSePtArQTq4h3-DQ49kxT70-pu7LUFMXTjCYoe26WUgyi9qRFM0SUp_vN-3ciz04rWyGhuGCvageZTTO2bIblKf3xICl12_U_bqCJ2j2SUeoyvFW5ZyUxkytKcCPMQN2TexloS0NFA949yHhezm5eYLZwBoqQc6GC_VrTjqNslpXi668nI56IQI2Mknp0JaC-yMV0sgtXIxXikfVxXfsyFQ081E51CfRT4K1eyIaJmRHi3xb2nxabNHWoAa5Vz132kAVtap3sM Sep 01 00:39:48 stefans-speaker volumio[22771]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 00:39:48 stefans-speaker volumio[22771]: info: New Spotify access token = BQCO90HUaBPHZDUq8SOZezPKtsl7ze5XwfsBJttKSwrZb2YUWqaLeYSePtArQTq4h3-DQ49kxT70-pu7LUFMXTjCYoe26WUgyi9qRFM0SUp_vN-3ciz04rWyGhuGCvageZTTO2bIblKf3xICl12_U_bqCJ2j2SUeoyvFW5ZyUxkytKcCPMQN2TexloS0NFA949yHhezm5eYLZwBoqQc6GC_VrTjqNslpXi668nI56IQI2Mknp0JaC-yMV0sgtXIxXikfVxXfsyFQ081E51CfRT4K1eyIaJmRHi3xb2nxabNHWoAa5Vz132kAVtap3sM Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Starting Shairport Sync Sep 01 00:39:48 stefans-speaker go-librespot[22977]: time="2026-09-01T00:39:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Starting Shairport Sync Sep 01 00:39:48 stefans-speaker sudo[22998]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:48 stefans-speaker sudo[22998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:39:48 stefans-speaker sudo[22998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Starting Shairport Sync Sep 01 00:39:48 stefans-speaker sudo[23003]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:48 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:39:48 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:39:48 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:39:48 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.844s CPU time. Sep 01 00:39:48 stefans-speaker sudo[23003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:39:48 stefans-speaker sudo[23003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:48 stefans-speaker sudo[23006]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:48 stefans-speaker sudo[23006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 00:39:48 stefans-speaker sudo[23006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:48 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:39:48 stefans-speaker sudo[22998]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:48 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 00:39:48 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 00:39:48 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Shairport-Sync Started Sep 01 00:39:48 stefans-speaker volumio[22771]: Error adding Membership: Error: addMembership EINVAL Sep 01 00:39:48 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 00:39:48 stefans-speaker sudo[23003]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:48 stefans-speaker sudo[23006]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:48 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:48 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Shairport-Sync Started Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Shairport-Sync Started Sep 01 00:39:48 stefans-speaker volumio[22771]: SPOTIFY: User informations: {"account_id":"zltuAJDW9c","country":"RO","display_name":"emanueluss","email":"emanuel.grecescu@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31mmtxvuufe76qdkk6caepwk3fcy"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/31mmtxvuufe76qdkk6caepwk3fcy","id":"31mmtxvuufe76qdkk6caepwk3fcy","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee852e7ddfcb02af85c1462f3412","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b822e7ddfcb02af85c1462f3412","width":64}],"product":"premium","type":"user","uri":"spotify:user:31mmtxvuufe76qdkk6caepwk3fcy"} Sep 01 00:39:48 stefans-speaker volumio[22771]: info: Spotify Successfully logged in Sep 01 00:39:48 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 00:39:48 stefans-speaker volumio[22771]: info: [1788215988662] CoreMusicLibrary::Adding element Spotify Sep 01 00:39:48 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 00:39:48 stefans-speaker volumio[22771]: Cannot find translation for source Spotify Sep 01 00:39:48 stefans-speaker sudo[23053]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:48 stefans-speaker sudo[23053]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 00:39:48 stefans-speaker sudo[23053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:48 stefans-speaker sudo[23054]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:48 stefans-speaker sudo[23053]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:48 stefans-speaker sudo[23054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 00:39:48 stefans-speaker sudo[23054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:48 stefans-speaker sudo[23054]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:49 stefans-speaker sudo[23057]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:49 stefans-speaker sudo[23057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 00:39:49 stefans-speaker sudo[23057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:49 stefans-speaker sudo[23057]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:49 stefans-speaker volumio[22771]: info: Upmpdcli Daemon Started Sep 01 00:39:49 stefans-speaker volumio[22771]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 00:39:49 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 00:39:49 stefans-speaker volumio[22771]: info: VolumeController:: Volume=70 Mute =false Sep 01 00:39:49 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioGetState Sep 01 00:39:49 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:49 stefans-speaker volumio[22771]: info: CoreStateMachine::pushState Sep 01 00:39:49 stefans-speaker volumio[22771]: info: CorePlayQueue::getTrack 0 Sep 01 00:39:49 stefans-speaker volumio[22771]: info: CoreCommandRouter::volumioPushState Sep 01 00:39:50 stefans-speaker mpd[22957]: 2026-09-01T00:39:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 00:39:50 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 00:39:50 stefans-speaker sudo[22912]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:50 stefans-speaker sudo[22922]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:50 stefans-speaker volumio[22771]: error: MPD error: The expression evaluated to a falsy value: Sep 01 00:39:50 stefans-speaker volumio[22771]: assert.ok(self.idling) Sep 01 00:39:50 stefans-speaker volumio[22771]: error: The expression evaluated to a falsy value: Sep 01 00:39:50 stefans-speaker volumio[22771]: assert.ok(self.idling) Sep 01 00:39:50 stefans-speaker volumio[22771]: error: updateQueue error: null Sep 01 00:39:50 stefans-speaker volumio[22771]: info: MPD running with PID22957 Sep 01 00:39:50 stefans-speaker volumio[22771]: ,establishing connection Sep 01 00:39:50 stefans-speaker volumio[22771]: info: Completed starting Core Plugins Sep 01 00:39:50 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:50 stefans-speaker volumio[22771]: info: ----- MyVolumio plugins startup ---- Sep 01 00:39:50 stefans-speaker volumio[22771]: info: ------------------------------------------- Sep 01 00:39:50 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 00:39:50 stefans-speaker volumio[22771]: error: updateQueue error: null Sep 01 00:39:51 stefans-speaker volumio[22771]: info: go-librespot daemon successfully initialized Sep 01 00:39:51 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 00:39:51 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:51 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:51 stefans-speaker go-librespot[23068]: go-librespot daemon starting... Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=debug msg="app state loaded" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02: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]" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02: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]" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02: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]" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=info msg="zeroconf server listening on port 43183" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=debug msg="obtained new client token: AAFKXYm78G/F35HZCSvXUOv1OYMzGpKnA1EQ6kB5s+pi4CehR/A6VDAA5n8FueW+CfSChqT6p+IV9w6vMl542K7n8VILmKLHMOLeCG4A5yGvGUneznourS/kvca8SV/lYZyTwzo6SaA29ke6iDwFmEJFbQrmHa/i0YxNa4rrrtAeH0lhJKKav4VmG/2GIo6jrHUSiu62OMDsWd+b2mhKlizad5UgsNy32qWZxvarXl7HPVXbMnR8S/+cJ2E=" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=debug msg="completed challenge" Sep 01 00:39:51 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:51] [connect] Successful connection Sep 01 00:39:51 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:51+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:51 stefans-speaker volumio-remote-updater[679]: [2026-09-01 00:39:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788215991 101 Sep 01 00:39:51 stefans-speaker volumio[22771]: 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: 2 Sep 01 00:39:52 stefans-speaker go-librespot[23069]: time="2026-09-01T00:39:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:52 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:52 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:53 stefans-speaker sudo[23079]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:53 stefans-speaker sudo[23079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:39:53 stefans-speaker sudo[23079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:53 stefans-speaker sudo[23081]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:39:53 stefans-speaker sudo[23081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 00:39:53 stefans-speaker sudo[23081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:39:53 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 00:39:53 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 00:39:53 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 00:39:53 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 00:39:53 stefans-speaker sudo[23081]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:53 stefans-speaker sudo[23079]: pam_unix(sudo:session): session closed for user root Sep 01 00:39:53 stefans-speaker volumio[22771]: info: Successfully started MPD Monitor Sep 01 00:39:53 stefans-speaker mpd_monitor.sh[23084]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 00:39:53 stefans-speaker volumio[22771]: info: Successfully started MPD Monitor Sep 01 00:39:54 stefans-speaker volumio[22771]: info: Initializing connection to go-librespot Websocket Sep 01 00:39:54 stefans-speaker volumio[22771]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:39:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 00:39:55 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:55 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:55 stefans-speaker go-librespot[23088]: go-librespot daemon starting... Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=debug msg="app state loaded" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:55 stefans-speaker volumio[22771]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02: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]" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02: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]" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02: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]" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=info msg="zeroconf server listening on port 41255" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=debug msg="obtained new client token: AAHj5rrBcxoKWQjMeLGOePFFj0qQzhuuXanfS8kttTaUOzSAX8YP1bDPyMbGQhYEoRZdRIW6Y7pSKrB05V8uHxtutSUemLfpemBgtk7y9ZJw7zwXemxnAEIEnZtlOOtVfwTgP3APVKeJgQBsO2dHIZG6Nd3mDDlb9vxUWARUBJYoHYJfYOGQM/bSPQstU0I/Q+j7XBuc6MU+MgiP30mJUFlIKCo/HF7FA/bgyRJ2zog6fH32PbPyDPPNvp4=" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=debug msg="completed challenge" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:55 stefans-speaker go-librespot[23089]: time="2026-09-01T00:39:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:57 stefans-speaker volumio[22771]: info: Initializing connection to go-librespot Websocket Sep 01 00:39:57 stefans-speaker volumio[22771]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 00:39:58 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 00:39:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 01 00:39:59 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:59 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:39:59 stefans-speaker go-librespot[23111]: go-librespot daemon starting... Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=debug msg="app state loaded" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02: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]" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02: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]" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02: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]" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=info msg="zeroconf server listening on port 35489" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=debug msg="obtained new client token: AAHKA9NcUo1jVUnevvRlXzDOFpI9JfXn/x1fbhtVNeNF+uGi/FVxM6BFQdCTN4fMH1V7cA/3ZA1Lv5Qfa3Y/u0Pqyqc/grWd1n+xzFXs/RzWDOwEpVknSqT7Jr6PdlV19cNAvLBauP7Sf+m9c0/hlR929frn5NBY/c+dft0j6wPyJI4YwsRvvQQO3+OiHB1WTrGeRvsMeH6BHJSwpvhrbwaYPTZTDMBYYF5fNGkoL0uECrUv7a9371J+5tQ=" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=debug msg="completed keyexchange" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=debug msg="completed challenge" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:39:59 stefans-speaker go-librespot[23112]: time="2026-09-01T00:39:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:39:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:39:59 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:39:59 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 00:39:59 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 00:39:59 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:59 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:39:59 stefans-speaker volumio[22771]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 00:39:59 stefans-speaker volumio[22771]: info: MyVolumio login type: Token Sep 01 00:39:59 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 00:39:59 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 00:40:00 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 00:40:00 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 00:40:00 stefans-speaker volumio[22771]: info: Streaming services startup Sep 01 00:40:00 stefans-speaker volumio[22771]: info: Starting Streaming Daemon Sep 01 00:40:01 stefans-speaker volumio[22771]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 00:40:01 stefans-speaker sudo[23120]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:40:01 stefans-speaker sudo[23120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:40:01 stefans-speaker sudo[23120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:40:01 stefans-speaker sudo[23120]: pam_unix(sudo:session): session closed for user root Sep 01 00:40:01 stefans-speaker volumio[22771]: info: Initializing connection to go-librespot Websocket Sep 01 00:40:01 stefans-speaker volumio[22771]: error: Cannot start Volumio Streaming Daemon Sep 01 00:40:01 stefans-speaker volumio[22771]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:40:01 stefans-speaker volumio[22771]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:40:01 stefans-speaker volumio[22771]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:40:01 stefans-speaker volumio[22771]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:40:01 stefans-speaker volumio[22771]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 00:40:02 stefans-speaker volumio[22771]: info: MyVolumio token set successfully Sep 01 00:40:02 stefans-speaker volumio[22771]: info: MYVOLUMIO: Adding device Sep 01 00:40:02 stefans-speaker volumio[22771]: info: MYVOLUMIO: Evaluating Server Sep 01 00:40:02 stefans-speaker volumio[22771]: info: MyVolumio status changed Sep 01 00:40:02 stefans-speaker volumio[22771]: info: Streaming services startup Sep 01 00:40:02 stefans-speaker volumio[22771]: info: Starting Streaming Daemon Sep 01 00:40:02 stefans-speaker volumio[22771]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 00:40:02 stefans-speaker volumio[22771]: info: Removing audio output: Sep 01 00:40:02 stefans-speaker volumio[22771]: info: Stoppping Tunnel 1 Sep 01 00:40:02 stefans-speaker sudo[23147]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:40:02 stefans-speaker sudo[23147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 00:40:02 stefans-speaker sudo[23147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:40:02 stefans-speaker sudo[23149]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:40:02 stefans-speaker sudo[23149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 00:40:02 stefans-speaker sudo[23149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:40:02 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 01 00:40:02 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:40:02 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:40:02 stefans-speaker go-librespot[23152]: go-librespot daemon starting... Sep 01 00:40:02 stefans-speaker 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. Sep 01 00:40:02 stefans-speaker 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. Sep 01 00:40:03 stefans-speaker sudo[23147]: pam_unix(sudo:session): session closed for user root Sep 01 00:40:03 stefans-speaker volumio[22771]: error: Cannot start Volumio Streaming Daemon Sep 01 00:40:03 stefans-speaker 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. Sep 01 00:40:03 stefans-speaker 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. Sep 01 00:40:03 stefans-speaker volumio[22771]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 00:40:03 stefans-speaker volumio[22771]: sudo: unable to resolve host stefans-speaker: System error Sep 01 00:40:03 stefans-speaker volumio[22771]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 00:40:03 stefans-speaker 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. Sep 01 00:40:03 stefans-speaker 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. Sep 01 00:40:03 stefans-speaker sudo[23149]: pam_unix(sudo:session): session closed for user root Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=debug msg="app state loaded" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:40:03 stefans-speaker volumio[22771]: info: Remote SSH Stopped Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02: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]" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02: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]" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02: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]" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=info msg="zeroconf server listening on port 36613" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=debug msg="obtained new client token: AAG7veaPI9uNhT6sDh3YasGmRgjgtF/XOKpy9pnW/KrbW0Xklrofi0QhsRj5zjZpsdGXmFAttQ5kidng2Hm3kTi3/ZPVB/HrvOWsPxgPePYTW6+/jIHai0Y/mUQE2qDdeA3CV6RhikPJBGsXIDJmykWketwM+LXU1GtlEhRlVe1NEWXp4IapHKrGhZkDHvnPRPoBv+o80ocy3WKxSNG/qE3zR0mo51gD1Qvuh/mF9yPQopTukFcpQva8rB0=" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=debug msg="completed keyexchange" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=debug msg="completed challenge" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:40:03 stefans-speaker go-librespot[23153]: time="2026-09-01T00:40:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:40:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:40:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:40:04 stefans-speaker volumio[22771]: info: Initializing connection to go-librespot Websocket Sep 01 00:40:04 stefans-speaker volumio[22771]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:40:04 stefans-speaker volumio[22771]: info: Setting Geolocation for MyVolumio to eu12 Sep 01 00:40:04 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:40:04 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:40:04 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:40:04 stefans-speaker volumio[22771]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 00:40:05 stefans-speaker volumio[22771]: info: Updating MyVolumio device info Sep 01 00:40:05 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:40:05 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:40:05 stefans-speaker volumio[22771]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 00:40:06 stefans-speaker volumio[22771]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 00:40:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 01 00:40:06 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:40:06 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:40:06 stefans-speaker go-librespot[23161]: go-librespot daemon starting... Sep 01 00:40:06 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:06+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:40:06 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:06+02:00" level=debug msg="app state loaded" Sep 01 00:40:06 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02: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]" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02: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]" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02: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]" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=info msg="zeroconf server listening on port 44905" Sep 01 00:40:07 stefans-speaker volumio[22771]: info: Initializing connection to go-librespot Websocket Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=debug msg="new websocket client" Sep 01 00:40:07 stefans-speaker volumio[22771]: info: Connection to go-librespot Websocket established Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=debug msg="obtained new client token: AAFJsrW4IBjpX94pSEMqBtQT1utE+jJyso2jDkVR7xCVA/TBHcpmsWcUatJpYs3Za/WCJrAWxrq9evBSLcNb97iKgdJn3R02+jFsm8XzGXpdtNiIUivPfqn/uSBeuQqEoclR4ZuEXpP73FWwFEJL4v9u5R8EG3Z3p+e1HkpJF+xzE4Nzqsc5FbqPZdF5IL0jyvS1pv86ITkUkniZVl6XQn9AHfVKUlcFD7fuZj0ZqUww7p9hTPrOwl53" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=debug msg="completed keyexchange" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=debug msg="completed challenge" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 00:40:07 stefans-speaker go-librespot[23162]: time="2026-09-01T00:40:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Sep 01 00:40:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 00:40:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 00:40:07 stefans-speaker volumio[22771]: info: Connection to go-librespot Websocket closed Sep 01 00:40:08 stefans-speaker volumio[22771]: info: MYVOLUMIO: Adding device Sep 01 00:40:08 stefans-speaker volumio[22771]: info: MYVOLUMIO: Evaluating Server Sep 01 00:40:10 stefans-speaker volumio[22771]: info: Getting Spotify volume Sep 01 00:40:10 stefans-speaker volumio[22771]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:40:10 stefans-speaker volumio[22771]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 00:40:10 stefans-speaker volumio[22771]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 00:40:10 stefans-speaker volumio[22771]: errno: -111, Sep 01 00:40:10 stefans-speaker volumio[22771]: code: 'ECONNREFUSED', Sep 01 00:40:10 stefans-speaker volumio[22771]: syscall: 'connect', Sep 01 00:40:10 stefans-speaker volumio[22771]: address: '127.0.0.1', Sep 01 00:40:10 stefans-speaker volumio[22771]: port: 9879, Sep 01 00:40:10 stefans-speaker volumio[22771]: response: undefined Sep 01 00:40:10 stefans-speaker volumio[22771]: } Sep 01 00:40:10 stefans-speaker volumio[22771]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 00:40:10 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 01 00:40:10 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:40:10 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 00:40:10 stefans-speaker go-librespot[23214]: go-librespot daemon starting... Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02:00" level=debug msg="app state loaded" Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02: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]" Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02: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]" Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02: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]" Sep 01 00:40:10 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:10+02:00" level=info msg="zeroconf server listening on port 44855" Sep 01 00:40:10 stefans-speaker sudo[23224]: volumio : unable to resolve host stefans-speaker: System error Sep 01 00:40:10 stefans-speaker sudo[23224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 00:39' Sep 01 00:40:11 stefans-speaker sudo[23224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 00:40:11 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:11+02:00" level=debug msg="obtained new client token: AAGFxF0/O4zQlSq9F/uU5CEtCQZET8ks3zo5uekIOQwdKXsquIzme4QZvFNeni20CZu7dXvcrpbYxgWChS3Sb5ch1INUVHbzYeet1xS7aj4zY9wqHt3WhDuGCiGwEmDlTvw9QH4Hx34nOFvlpiGSnVG5ozmN/k15eeDizfOcEXdWC1dvDZ0xUb0aCeVIomGw8cN8Cvi6MjznMQyzXWcpC/YmtPw8uJNJ67SAp2nkYoijLA1NgYWBS5tW" Sep 01 00:40:11 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 00:40:11 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:11+02:00" level=debug msg="completed keyexchange" Sep 01 00:40:11 stefans-speaker go-librespot[23215]: time="2026-09-01T00:40:11+02:00" level=debug msg="completed challenge" 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"