Sep 01 01:12:00 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:12:00 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Streaming services startup Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Starting Streaming Daemon Sep 01 01:12:00 stefans-speaker sudo[13320]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:00 stefans-speaker volumio[12982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:12:00 stefans-speaker sudo[13320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:00 stefans-speaker sudo[13320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:00 stefans-speaker sudo[13320]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:00 stefans-speaker volumio[12982]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:00 stefans-speaker volumio[12982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:00 stefans-speaker volumio[12982]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:00 stefans-speaker volumio[12982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12:00 stefans-speaker volumio[12982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:01 stefans-speaker volumio[12982]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MyVolumio token set successfully Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MYVOLUMIO: Adding device Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MYVOLUMIO: Evaluating Server Sep 01 01:12:02 stefans-speaker volumio[12982]: info: MyVolumio status changed Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Streaming services startup Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Starting Streaming Daemon Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Removing audio output: Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Stoppping Tunnel 1 Sep 01 01:12:02 stefans-speaker sudo[13349]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:02 stefans-speaker sudo[13349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:02 stefans-speaker sudo[13349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:02 stefans-speaker sudo[13351]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:02 stefans-speaker sudo[13351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:12:02 stefans-speaker sudo[13351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:02 stefans-speaker sudo[13349]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:02 stefans-speaker volumio[12982]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:02 stefans-speaker volumio[12982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:02 stefans-speaker volumio[12982]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:02 stefans-speaker volumio[12982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12: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 01:12: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 01:12: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 01:12: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 01:12: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 01:12: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 01:12:02 stefans-speaker sudo[13351]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Remote SSH Stopped Sep 01 01:12:02 stefans-speaker volumio[12982]: info: Setting Geolocation for MyVolumio to eu4 Sep 01 01:12:02 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:02 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:02 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 01:12:03 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:03 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:03 stefans-speaker go-librespot[13354]: go-librespot daemon starting... Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="app state loaded" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:03 stefans-speaker volumio[12982]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+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 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+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 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="zeroconf server listening on port 43581" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="obtained new client token: AAFRUiCmkfHuD0BSAWpQm2+OJ7Y3FMemb4xRRw5a5pc2VLQ+/aoRNU8MKu4Z2yCgN3qaSSACxejqGDxXwsAuBGDwQBf5j86RcQgJ151kWK/LG5isXvY4bacsK0gEpOIehK2qW2x9UrATY2zBqIEcagSY6SiIPzQArnyyppxFhSl7SJQunxQERvH3Ar0BSo9vFKSQihuGjzuDtn15sBMQY2Q+HSxmOm/UA4O92s7wOQvifoSqnxHaiCf9xCw=" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Updating MyVolumio device info Sep 01 01:12:03 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker volumio[12982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="completed challenge" Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12:03+02:00" level=debug msg="new websocket client" Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Connection to go-librespot Websocket established Sep 01 01:12:03 stefans-speaker go-librespot[13355]: time="2026-09-01T01:12: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 01:12:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:03 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:03 stefans-speaker volumio[12982]: info: Connection to go-librespot Websocket closed Sep 01 01:12:03 stefans-speaker volumio[12982]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:12:06 stefans-speaker volumio[12982]: info: Getting Spotify volume Sep 01 01:12:06 stefans-speaker volumio[12982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:06 stefans-speaker volumio[12982]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:06 stefans-speaker volumio[12982]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 01:12:06 stefans-speaker volumio[12982]: errno: -111, Sep 01 01:12:06 stefans-speaker volumio[12982]: code: 'ECONNREFUSED', Sep 01 01:12:06 stefans-speaker volumio[12982]: syscall: 'connect', Sep 01 01:12:06 stefans-speaker volumio[12982]: address: '127.0.0.1', Sep 01 01:12:06 stefans-speaker volumio[12982]: port: 9879, Sep 01 01:12:06 stefans-speaker volumio[12982]: response: undefined Sep 01 01:12:06 stefans-speaker volumio[12982]: } Sep 01 01:12:06 stefans-speaker volumio[12982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 01:12:07 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:07 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:07 stefans-speaker go-librespot[13374]: go-librespot daemon starting... Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="app state loaded" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+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 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+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 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="zeroconf server listening on port 34539" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="obtained new client token: AAE94UV6iWd99kqXaRIdxIgB1lnYwjiYW9jMoTrquj4GbUdNCRTlUvZVvlD83AK8KQYmd2AoXHqt5/Ax3LjDdPDxVT8p2gXEuHAuxQrCMKQTTt5m8chPQXWV0s+SJBx0JmaHAH6pud8WJ1cNOhDeIe6xL9+R98by5E2QssAK1FnTq02mYvtFxSdPH4JdZow2f7dsqbEj+8A3XqeFN+pQWvfUVhWg2u7zFX2vi+oYv2Qk1tW0B6+k4AszYhg=" Sep 01 01:12:07 stefans-speaker sudo[13384]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:07 stefans-speaker sudo[13384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:11' Sep 01 01:12:07 stefans-speaker sudo[13384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=debug msg="completed challenge" Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12:07+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:07 stefans-speaker sudo[13384]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:07 stefans-speaker volumio[12982]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:07 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 01:12:07 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 01:12:07 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:07 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 01:12:07 stefans-speaker systemd[1]: volumio.service: Consumed 32.398s CPU time. Sep 01 01:12:07 stefans-speaker go-librespot[13375]: time="2026-09-01T01:12: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 01:12:07 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:12:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:07 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:07 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:12:08 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 301. Sep 01 01:12:08 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:12:08 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 01:12:08 stefans-speaker systemd[1]: volumio.service: Consumed 32.398s CPU time. Sep 01 01:12:08 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 01:12:08 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ----- Volumio3 ---- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ----- System startup ---- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: MYVOLUMIO Environment detected Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Plugin folders cleanup Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category audio_interface Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category miscellanea Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category music_service Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category plugins.json Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category system_controller Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category user_interface Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning into folder /data/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Scanning category music_service Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Plugin folders cleanup completed Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ----- Core plugins startup ---- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Loading plugins from folder /data/plugins/ Sep 01 01:12:10 stefans-speaker volumio[13396]: info: Loading plugin "system"... Sep 01 01:12:11 stefans-speaker volumio[13396]: info: Loading plugin "appearance"... Sep 01 01:12:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 01:12:11 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:11 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:11 stefans-speaker go-librespot[13423]: go-librespot daemon starting... Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="app state loaded" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="zeroconf server listening on port 43787" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="obtained new client token: AAG37slzB8Uyw3GXIat8qLxMkx5jxggH6iUE97FDs2apQ6Y+wq/oqmum15TiIIP77oklQo2ivzBu+VuqgnkLdBt29hlFeht4Se7WMegWhKffCr2JqpL/JibWF+xWQIA5BhgLYXZuhSEolrQ1EURDsLL8irwK4I+Odvj9Lcpfe//7E1DuwxN92R+ovcZDykOAHDnDTcVbyMPepymXpJkzmZbo8E4FXqXhZYO/ISZKkriwVvH/vURAxJJVdzI=" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=debug msg="completed challenge" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:11 stefans-speaker go-librespot[13424]: time="2026-09-01T01:12:11+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 01:12:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:11 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "network"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Refreshing Cached IP Addresses Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "services"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "alsa_controller"... Sep 01 01:12:12 stefans-speaker sudo[13434]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:12 stefans-speaker sudo[13432]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:12 stefans-speaker sudo[13432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:12:12 stefans-speaker sudo[13434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:12:12 stefans-speaker sudo[13434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:12 stefans-speaker sudo[13432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:12 stefans-speaker sudo[13441]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:12 stefans-speaker sudo[13434]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:12 stefans-speaker sudo[13432]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:12 stefans-speaker sudo[13441]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:12:12 stefans-speaker sudo[13441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:12 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "wizard"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "networkfs"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Starting Udev Watcher for removable devices Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Ignoring mount for partition: boot Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Ignoring mount for partition: volumio Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Ignoring mount for partition: volumio_data Sep 01 01:12:12 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "upnp"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: [1788217932384] Starting Upmpd Daemon Sep 01 01:12:12 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "my_music"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "mpd"... Sep 01 01:12:12 stefans-speaker volumio[13396]: info: Loading plugin "upnp_browser"... Sep 01 01:12:12 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:12] [connect] Successful connection Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Starting UPNP Browser Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "alarm-clock"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "airplay_emulation"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "last_100"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "webradio"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "i2s_dacs"... Sep 01 01:12:14 stefans-speaker volumio[13396]: info: I2S DAC not set, start Auto-detection Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "volumiodiscovery"... Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker volumio[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:14 stefans-speaker node[13396]: *** WARNING *** For more information see Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:12:14 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:14 stefans-speaker volumio[13396]: info: Loading plugin "spop"... Sep 01 01:12:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 01:12:15 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:15 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:15 stefans-speaker go-librespot[13470]: go-librespot daemon starting... Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="app state loaded" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+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 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+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 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+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 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=info msg="zeroconf server listening on port 44041" Sep 01 01:12:15 stefans-speaker sudo[13441]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="obtained new client token: AAH7eWQdS+UdYnzpX3Jgw/5/uJjb+JzWTYSp9rYcZBMvnCZf/hWdqjEmYIuNw4sQU8l/4en+bO2sfhfHhPs0dP4/m8HSKDRYWMU21u3pWEbeBhkPXAtKUFvMIFyobFzxSEIMGz3CF6aaMPEb0oalO7EDGenPE4u7FtXB1l0E+RXCr7LEwB+t4bXJxgRsA4Fr1ktTiwNR47njaAzlY5FOVRALkh0CDybdHflPMYU18iAKeZKsZCWkOalN8vc=" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=debug msg="completed challenge" Sep 01 01:12:15 stefans-speaker volumio[13396]: info: Loading plugin "ytcr"... Sep 01 01:12:15 stefans-speaker go-librespot[13471]: time="2026-09-01T01:12:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Sep 01 01:12:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "outputs"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "albumart"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Plugin example_plugin is not enabled Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "inputs"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "updater_comm"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Plugin mpdemulation is not enabled Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "rest_api"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading plugin "websocket"... Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Loading i18n strings for locale nl Sep 01 01:12:17 stefans-speaker volumio[13396]: Updating browse sources language Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::initPlayerControls Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: Express server listening on port 3000 Sep 01 01:12:17 stefans-speaker volumio[13396]: [Metrics] WebUI: 8s 110.08ms Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::resetVolumioState Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::getcurrentVolume Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Cannot read play queue from file Sep 01 01:12:17 stefans-speaker volumio[13396]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:12:17 stefans-speaker volumio[13478]: Forking 3 albumart workers Sep 01 01:12:17 stefans-speaker volumio[13396]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreStateMachine::updateTrackBlock Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrackBlock Sep 01 01:12:17 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::setRandom null Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Setting Device type: Raspberry PI Sep 01 01:12:18 stefans-speaker volumio[13396]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Completed loading Core Plugins Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Preparing to generate the ALSA configuration file Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Output device has changed, restarting MPD Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Output device has changed, restarting Shairport Sync Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker sudo[13534]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker sudo[13536]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker sudo[13534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:12:18 stefans-speaker sudo[13534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker sudo[13536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:12:18 stefans-speaker sudo[13536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker sudo[13534]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: ___________ START PLUGINS ___________ Sep 01 01:12:18 stefans-speaker volumio[13396]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Creating MPD Configuration file Sep 01 01:12:18 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:18 stefans-speaker volumio[13396]: info: [1788217938504] CoreMusicLibrary::Adding element Media Servers Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:18 stefans-speaker volumio[13396]: info: UPNP Browser: Client initialized successfully Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker sudo[13544]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker sudo[13546]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:12:18 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.service: Consumed 4.598s CPU time. Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:12:18 stefans-speaker sudo[13544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:12:18 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker sudo[13544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:12:18 stefans-speaker sudo[13544]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:18 stefans-speaker sudo[13546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:12:18 stefans-speaker sudo[13546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:18 stefans-speaker volumio[13396]: info: [1788217938661] CoreMusicLibrary::Adding element Last_100 Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:18 stefans-speaker volumio[13396]: info: [1788217938666] CoreMusicLibrary::Adding element Webradio Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:18 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Initializing BBC Radios Sep 01 01:12:18 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:12:18 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:12:18 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:12:18 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:12:18 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:12:18 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:18 stefans-speaker volumio[13396]: info: Creating Spotify config file Sep 01 01:12:18 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:19 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 01 01:12:19 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:19 stefans-speaker sudo[13565]: root : unable to resolve host stefans-speaker: System error Sep 01 01:12:19 stefans-speaker sudo[13565]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:19 stefans-speaker sudo[13565]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:12:19 stefans-speaker sudo[13565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:12:19 stefans-speaker sudo[13565]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:19 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:19 stefans-speaker go-librespot[13574]: go-librespot daemon starting... Sep 01 01:12:19 stefans-speaker go-librespot[13576]: time="2026-09-01T01:12:19+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:19 stefans-speaker go-librespot[13576]: time="2026-09-01T01:12:19+02:00" level=info msg="zeroconf server listening on port 43703" Sep 01 01:12:19 stefans-speaker volumio[13396]: info: Volumio Calling Home Sep 01 01:12:20 stefans-speaker volumio[13494]: Starting albumart workers Sep 01 01:12:20 stefans-speaker volumio[13495]: Starting albumart workers Sep 01 01:12:20 stefans-speaker volumio[13496]: Starting albumart workers Sep 01 01:12:20 stefans-speaker volumio[13396]: info: MPD Permissions set Sep 01 01:12:20 stefans-speaker volumio[13396]: info: MPD Permissions set Sep 01 01:12:20 stefans-speaker volumio[13396]: info: Volumio called home Sep 01 01:12:20 stefans-speaker volumio[13396]: info: Spotify config file written Sep 01 01:12:20 stefans-speaker volumio[13396]: 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 01:12:20 stefans-speaker sudo[13589]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker sudo[13589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:12:20 stefans-speaker sudo[13589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 01:12:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Killing process 13579 (go-librespot) with signal SIGKILL. Sep 01 01:12:20 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 01:12:20 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:20 stefans-speaker go-librespot[13596]: go-librespot daemon starting... Sep 01 01:12:20 stefans-speaker sudo[13589]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:20 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=debug msg="app state loaded" Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:20 stefans-speaker volumio[13396]: info: No need to fix Spotify hosts Sep 01 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+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 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+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 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+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 01:12:20 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:20+02:00" level=info msg="zeroconf server listening on port 38703" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="obtained new client token: AAHfqGWmEiw4MIAYBPTAkFWxtgCAiBUtuG9Zdj5hUy3PX9y8gFFNSfx22Inf+0ACxvjKcnaQWLfcD5IhzWNTajAu+l5bBkcQSyiCmDkloXbo5zYEjqKUpxMiBr6LyedzP5pGGh92EsxLmn/VOkduKR1ePmSW6xx+l3lf2x76r7z/nploiuTx7d51euYwq8rAyuuM2qCKlUri7GLBEtiLI60ecifqEUZn1+tV/OYiMxBBC8uRulmACa/R" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=debug msg="completed challenge" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:21 stefans-speaker go-librespot[13602]: time="2026-09-01T01:12:21+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 01:12:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:21 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:21 stefans-speaker volumio[13396]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:12:21 stefans-speaker volumio[13396]: SPOTIFY: BQB6FU-p1L_I6B_YhKhDi2Vjj0Hi742tUfMHH1e_FKOuLMAWQVX-EeoUtsrino_3oMMpIuHO-6wROH8N_mSnmcvQc9ubovrAV_2TEBlflDitKffdAbONTGscACVPAm4bpNFXDLw-CWgRU6cR7G0oS5zzLB-e7o0XMBC-3xbWyxreFLYd1pxLg1nV1Dd0BhT6pWlpZBK23cZcckmNlEAPtbSA9aCaKNJF3MD-0gzqIzEGtbULj1wVihAx96bD0Q7bWrlEmRzV4S0sBXVMA4mdxg9b5NHqWPB_TyZMsikbGykBcorlvqpRGKFACDVipFU Sep 01 01:12:21 stefans-speaker volumio[13396]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:12:21 stefans-speaker volumio[13396]: info: New Spotify access token = BQB6FU-p1L_I6B_YhKhDi2Vjj0Hi742tUfMHH1e_FKOuLMAWQVX-EeoUtsrino_3oMMpIuHO-6wROH8N_mSnmcvQc9ubovrAV_2TEBlflDitKffdAbONTGscACVPAm4bpNFXDLw-CWgRU6cR7G0oS5zzLB-e7o0XMBC-3xbWyxreFLYd1pxLg1nV1Dd0BhT6pWlpZBK23cZcckmNlEAPtbSA9aCaKNJF3MD-0gzqIzEGtbULj1wVihAx96bD0Q7bWrlEmRzV4S0sBXVMA4mdxg9b5NHqWPB_TyZMsikbGykBcorlvqpRGKFACDVipFU Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:21 stefans-speaker volumio[13396]: info: Starting Shairport Sync Sep 01 01:12:21 stefans-speaker sudo[13629]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:21 stefans-speaker sudo[13629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:12:21 stefans-speaker sudo[13629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:21 stefans-speaker sudo[13631]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:21 stefans-speaker sudo[13631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:12:21 stefans-speaker sudo[13631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:21 stefans-speaker sudo[13633]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:21 stefans-speaker sudo[13633]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:12:21 stefans-speaker sudo[13633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:21 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:12:21 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:12:21 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:21 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.825s CPU time. Sep 01 01:12:21 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:21 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:21 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:21 stefans-speaker sudo[13629]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:21 stefans-speaker sudo[13633]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:21 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:12:21 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:12:21 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:22 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:12:22 stefans-speaker sudo[13631]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Shairport-Sync Started Sep 01 01:12:22 stefans-speaker volumio[13396]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Shairport-Sync Started Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Shairport-Sync Started Sep 01 01:12:22 stefans-speaker volumio[13396]: 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 01:12:22 stefans-speaker volumio[13396]: info: Spotify Successfully logged in Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:22 stefans-speaker volumio[13396]: info: [1788217942204] CoreMusicLibrary::Adding element Spotify Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:22 stefans-speaker volumio[13396]: Cannot find translation for source Spotify Sep 01 01:12:22 stefans-speaker sudo[13668]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:22 stefans-speaker sudo[13668]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:12:22 stefans-speaker sudo[13670]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:22 stefans-speaker sudo[13668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:22 stefans-speaker sudo[13670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:12:22 stefans-speaker sudo[13670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:22 stefans-speaker sudo[13668]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker sudo[13670]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker sudo[13674]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:22 stefans-speaker sudo[13674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:12:22 stefans-speaker sudo[13674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:22 stefans-speaker sudo[13674]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:22 stefans-speaker volumio[13396]: info: Upmpdcli Daemon Started Sep 01 01:12:22 stefans-speaker volumio[13396]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:22 stefans-speaker volumio[13396]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreStateMachine::pushState Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:22 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:23 stefans-speaker mpd[13577]: 2026-09-01T01:12:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:12:23 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:12:23 stefans-speaker sudo[13536]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:23 stefans-speaker sudo[13546]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:23 stefans-speaker volumio[13396]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:12:23 stefans-speaker volumio[13396]: assert.ok(self.idling) Sep 01 01:12:23 stefans-speaker volumio[13396]: error: The expression evaluated to a falsy value: Sep 01 01:12:23 stefans-speaker volumio[13396]: assert.ok(self.idling) Sep 01 01:12:23 stefans-speaker volumio[13396]: error: updateQueue error: null Sep 01 01:12:23 stefans-speaker volumio[13396]: info: MPD running with PID13577 Sep 01 01:12:23 stefans-speaker volumio[13396]: ,establishing connection Sep 01 01:12:23 stefans-speaker volumio[13396]: info: Completed starting Core Plugins Sep 01 01:12:23 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:23 stefans-speaker volumio[13396]: info: ----- MyVolumio plugins startup ---- Sep 01 01:12:23 stefans-speaker volumio[13396]: info: ------------------------------------------- Sep 01 01:12:23 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:12:23 stefans-speaker volumio[13396]: error: updateQueue error: null Sep 01 01:12:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 01:12:24 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:24 stefans-speaker volumio[13396]: info: go-librespot daemon successfully initialized Sep 01 01:12:24 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:24 stefans-speaker go-librespot[13684]: go-librespot daemon starting... Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=debug msg="app state loaded" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+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 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+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 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+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 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=info msg="zeroconf server listening on port 34689" Sep 01 01:12:24 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:24+02:00" level=debug msg="obtained new client token: AAGlE+mTd/iJxnvI5jyX0eMLvLIVnQxiMHbHaz6QIyas0036Qw6dapPPhVySVdpkVus38VsAruxQ69lLw8YsiKgD54gUibJ/tsvgQ7gqHokVTo0ChslDW6jtXHQdWWnsIddxW+EwygoOF8u9jpXl1yr23tABuDlF5g189YMp7znP35vChzLbTPmN6U1t7lHbirvm4IW8A0V1HKmTqO1Fsy+aOOhBzb8ywwfimDhRjojxXiNpjtHgUgmSnbI=" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=debug msg="completed challenge" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:25 stefans-speaker go-librespot[13685]: time="2026-09-01T01:12:25+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 01:12:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:25 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:25 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:25] [connect] Successful connection Sep 01 01:12:25 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788217945 101 Sep 01 01:12:25 stefans-speaker volumio[13396]: 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 01:12:26 stefans-speaker sudo[13695]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:26 stefans-speaker sudo[13697]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:26 stefans-speaker sudo[13695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:12:26 stefans-speaker sudo[13695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:26 stefans-speaker sudo[13697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:12:26 stefans-speaker sudo[13697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:26 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:12:26 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:12:26 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:12:26 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:12:26 stefans-speaker sudo[13697]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:26 stefans-speaker sudo[13695]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:27 stefans-speaker mpd_monitor.sh[13700]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Successfully started MPD Monitor Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Successfully started MPD Monitor Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:27 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 01:12:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:28 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:28 stefans-speaker go-librespot[13704]: go-librespot daemon starting... Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="app state loaded" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12: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 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12: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 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12: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 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="zeroconf server listening on port 38339" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="obtained new client token: AAGYRLuIEOfZ2NUn2S4FVkyn0DcqOa+3izR3dwfh4vUrQMdrHEM9PDr2fJ3GnTjsxdwcWe0nXULXIdhPUMM/47HGGqb89ASSLCvD9od5l/C1ENshYlI3TlmN5vCAyh0IkYUD9sJk7Xjl8U9uuevPndloQG60Kay1JFMt64GwBvOJ6pCggUL5w98iMWtgSNqChzl03Pjlh5vCSwp+0p0gK8LOzGC48NK/R/Et3W8qMC/Fq9tfBssA0Z1zFxU=" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:28 stefans-speaker volumio[13396]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=debug msg="completed challenge" Sep 01 01:12:28 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:28+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:29 stefans-speaker go-librespot[13705]: time="2026-09-01T01:12:29+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 01:12:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:30 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:30 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:12:32 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:12:32 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 01:12:32 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:32 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:32 stefans-speaker go-librespot[13714]: go-librespot daemon starting... Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="app state loaded" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+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 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+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 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="zeroconf server listening on port 39627" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="obtained new client token: AAGtx91NHfUW0SN/GcZAj2iRgR94ZIdKFkyXjUIiZK+crPHEnZ4LNTPL5TUrgDtDkHUS2ITPZdqcKaUOxbmleRsIP/AMuSY2VOOqc0jboTpkOgVtsgW8K47R0TBBtmXCDMiJi/bApddegTTvQQqmj8qxYytbSkxyPjSmefCBKvGupeBP4H4YvJXOmMM9HqU+09Nd3X1KFRuUEK/CX/nZKXpt87MA90Vtzm9hxvuILYjBa2aJSraEYjAWDso=" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=debug msg="completed challenge" Sep 01 01:12:32 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:32+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:33 stefans-speaker go-librespot[13715]: time="2026-09-01T01:12:33+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 01:12:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:12:33 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:33 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:33 stefans-speaker volumio[13396]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:12:33 stefans-speaker volumio[13396]: info: MyVolumio login type: Token Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:12:33 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:12:34 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:12:34 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Streaming services startup Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Starting Streaming Daemon Sep 01 01:12:34 stefans-speaker volumio[13396]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:12:34 stefans-speaker sudo[13737]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:34 stefans-speaker sudo[13737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:34 stefans-speaker sudo[13737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:34 stefans-speaker sudo[13737]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:34 stefans-speaker volumio[13396]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:34 stefans-speaker volumio[13396]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:34 stefans-speaker volumio[13396]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:34 stefans-speaker volumio[13396]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12:34 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:34 stefans-speaker volumio[13396]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MyVolumio token set successfully Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MYVOLUMIO: Adding device Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MYVOLUMIO: Evaluating Server Sep 01 01:12:35 stefans-speaker volumio[13396]: info: MyVolumio status changed Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Streaming services startup Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Starting Streaming Daemon Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Removing audio output: Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Stoppping Tunnel 1 Sep 01 01:12:35 stefans-speaker sudo[13765]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:35 stefans-speaker sudo[13765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:12:35 stefans-speaker sudo[13765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:35 stefans-speaker sudo[13767]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:35 stefans-speaker sudo[13767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:12:35 stefans-speaker sudo[13767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:35 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 01:12:35 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 01:12:35 stefans-speaker sudo[13765]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:35 stefans-speaker volumio[13396]: error: Cannot start Volumio Streaming Daemon Sep 01 01:12:35 stefans-speaker volumio[13396]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:12:35 stefans-speaker volumio[13396]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:35 stefans-speaker volumio[13396]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:12:35 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 01:12:35 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 01:12:35 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 01:12:35 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 01:12:35 stefans-speaker sudo[13767]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Remote SSH Stopped Sep 01 01:12:35 stefans-speaker volumio[13396]: info: Setting Geolocation for MyVolumio to eu5 Sep 01 01:12:35 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:35 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:35 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:36 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 01:12:36 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:36 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:36 stefans-speaker go-librespot[13770]: go-librespot daemon starting... Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="app state loaded" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:36 stefans-speaker volumio[13396]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12: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 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12: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 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12: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 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="zeroconf server listening on port 44625" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="obtained new client token: AAGXRXUSkG+2r+m8s+O6Itkd75cq5MXRLo/mil+akZqlQdzxSBCFRKZhnl1AOnPu9KrEOpqBKu254GUlM6vMmuP1cFlI1IWtJv1XlqdOYEhTtsVbQqISlo0cMAQWgIIY0Bqd0pO0fLzousqgb07JhPNkilffObiQFk2b4nIZWcaNFnD8tm5aOloHGqlc2TswSRMVrv+2w4DAK/5NWqPTgQuGwgQD95DAGd6S7HzFspgMme//1yt9nk72nUI=" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=debug msg="completed challenge" Sep 01 01:12:36 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:36+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:36 stefans-speaker volumio[13396]: info: Updating MyVolumio device info Sep 01 01:12:36 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:36 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:36 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:37 stefans-speaker go-librespot[13771]: time="2026-09-01T01:12:37+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 01:12:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:37 stefans-speaker volumio[13396]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:12:37 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:37 stefans-speaker volumio[13396]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:12:40 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 01:12:40 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:40 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:40 stefans-speaker go-librespot[13778]: go-librespot daemon starting... Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=debug msg="app state loaded" Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:40 stefans-speaker volumio[13396]: info: MYVOLUMIO: Adding device Sep 01 01:12:40 stefans-speaker volumio[13396]: info: MYVOLUMIO: Evaluating Server Sep 01 01:12:40 stefans-speaker volumio[13396]: info: Initializing connection to go-librespot Websocket Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=debug msg="new websocket client" Sep 01 01:12:40 stefans-speaker volumio[13396]: info: Connection to go-librespot Websocket established Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+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 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+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 01:12:40 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:40+02:00" level=info msg="zeroconf server listening on port 46111" Sep 01 01:12:41 stefans-speaker volumio[13396]: info: Setting Geolocation for MyVolumio to eu2 Sep 01 01:12:41 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:41 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:41 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:41 stefans-speaker volumio[13396]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:12:42 stefans-speaker volumio[13396]: info: Updating MyVolumio device info Sep 01 01:12:42 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:42 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:42 stefans-speaker volumio[13396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:43 stefans-speaker volumio[13396]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:12:43 stefans-speaker volumio[13396]: info: Getting Spotify volume Sep 01 01:12:43 stefans-speaker volumio[13396]: 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 01:12:43 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:43 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:43 stefans-speaker volumio[13396]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Sep 01 01:12:43 stefans-speaker volumio[13396]: SPOTIFY: SPOTIFY VOLUME undefined Sep 01 01:12:43 stefans-speaker volumio[13396]: SPOTIFY: VOLUMIO VOLUME 70 Sep 01 01:12:43 stefans-speaker volumio[13396]: info: Aligning Spotify Volume to Volumio Volume Sep 01 01:12:43 stefans-speaker volumio[13396]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:43 stefans-speaker volumio[13396]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:43 stefans-speaker volumio[13396]: info: Setting Spotify Volume from Volumio: 70 Sep 01 01:12:45 stefans-speaker volumio[13396]: SPOTIFY: SETTING SPOTIFY VOLUME 70 Sep 01 01:12:45 stefans-speaker volumio[13396]: info: Sending Spotify command with payload to local API: /player/volume Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="obtained new client token: AAHurpmg362lM4gpKFfSy5fFPeZYbLZ3g2fSNbLOrKkaGLRAggJhXf2UIIimzSDJnmDzvhJ6ar8stpXSi57d4xByqMZuP+sXAUFHkABVdX05lyjXlXa2do4QeZt4Vq6NIZtqec4Ze2MWZbo19YdfeWmYo5RQPGiyEzXRaYbx1XkMTGtl3Gv39sPFHp9YRCg0bxFNw3/GHVImd3VP7z4yqdBIdiwJgIWv7b5uKGHg5NoubowSDfRzYW5zVCA=" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=debug msg="completed challenge" Sep 01 01:12:45 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12:45+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:46 stefans-speaker go-librespot[13779]: time="2026-09-01T01:12: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 01:12:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:46 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:46 stefans-speaker volumio[13396]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Sep 01 01:12:46 stefans-speaker volumio[13396]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:46 stefans-speaker volumio[13396]: Error: socket hang up Sep 01 01:12:46 stefans-speaker volumio[13396]: at connResetException (node:internal/errors:720:14) Sep 01 01:12:46 stefans-speaker volumio[13396]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 01 01:12:46 stefans-speaker volumio[13396]: at Socket.emit (node:events:526:35) Sep 01 01:12:46 stefans-speaker volumio[13396]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 01:12:46 stefans-speaker volumio[13396]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 01 01:12:46 stefans-speaker volumio[13396]: code: 'ECONNRESET', Sep 01 01:12:46 stefans-speaker volumio[13396]: response: undefined Sep 01 01:12:46 stefans-speaker volumio[13396]: } Sep 01 01:12:46 stefans-speaker volumio[13396]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:12:46 stefans-speaker sudo[13833]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:46 stefans-speaker sudo[13833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:11' Sep 01 01:12:46 stefans-speaker sudo[13833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:47 stefans-speaker sudo[13833]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:47 stefans-speaker volumio[13396]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:47 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 01:12:47 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 01:12:47 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:47 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 01:12:47 stefans-speaker systemd[1]: volumio.service: Consumed 33.761s CPU time. Sep 01 01:12:47 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:12:47 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:12:47 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 302. Sep 01 01:12:47 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:12:47 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 01:12:47 stefans-speaker systemd[1]: volumio.service: Consumed 33.761s CPU time. Sep 01 01:12:47 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 01:12:47 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:12:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 01:12:49 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:49 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:49 stefans-speaker go-librespot[13867]: go-librespot daemon starting... Sep 01 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+02:00" level=debug msg="app state loaded" Sep 01 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+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 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+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 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+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 01:12:49 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:49+02:00" level=info msg="zeroconf server listening on port 33411" Sep 01 01:12:49 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:12:49 stefans-speaker volumio[13852]: info: ----- Volumio3 ---- Sep 01 01:12:49 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:12:49 stefans-speaker volumio[13852]: info: ----- System startup ---- Sep 01 01:12:49 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:12:50 stefans-speaker volumio[13852]: info: MYVOLUMIO Environment detected Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Plugin folders cleanup Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category audio_interface Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category miscellanea Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category music_service Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category plugins.json Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category system_controller Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category user_interface Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning into folder /data/plugins/ Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Scanning category music_service Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Plugin folders cleanup completed Sep 01 01:12:50 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:12:50 stefans-speaker volumio[13852]: info: ----- Core plugins startup ---- Sep 01 01:12:50 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Loading plugins from folder /data/plugins/ Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Loading plugin "system"... Sep 01 01:12:50 stefans-speaker volumio[13852]: info: Loading plugin "appearance"... Sep 01 01:12:51 stefans-speaker volumio[13852]: info: Loading plugin "network"... Sep 01 01:12:51 stefans-speaker volumio[13852]: info: Refreshing Cached IP Addresses Sep 01 01:12:51 stefans-speaker sudo[13889]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:51 stefans-speaker sudo[13891]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:51 stefans-speaker volumio[13852]: info: Loading plugin "services"... Sep 01 01:12:51 stefans-speaker sudo[13889]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:12:51 stefans-speaker sudo[13891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:12:51 stefans-speaker volumio[13852]: info: Loading plugin "alsa_controller"... Sep 01 01:12:51 stefans-speaker sudo[13891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:51 stefans-speaker sudo[13889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:51 stefans-speaker sudo[13891]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:51 stefans-speaker sudo[13889]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:51 stefans-speaker sudo[13898]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:51 stefans-speaker sudo[13898]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:12:51 stefans-speaker sudo[13898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:51 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:51 stefans-speaker volumio[13852]: info: Loading plugin "wizard"... Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Loading plugin "networkfs"... Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Starting Udev Watcher for removable devices Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Ignoring mount for partition: boot Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Ignoring mount for partition: volumio Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Ignoring mount for partition: volumio_data Sep 01 01:12:52 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Loading plugin "upnp"... Sep 01 01:12:52 stefans-speaker volumio[13852]: info: [1788217972079] Starting Upmpd Daemon Sep 01 01:12:52 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Loading plugin "my_music"... Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Loading plugin "mpd"... Sep 01 01:12:52 stefans-speaker volumio[13852]: info: Loading plugin "upnp_browser"... Sep 01 01:12:52 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:52] [connect] Successful connection Sep 01 01:12:53 stefans-speaker volumio[13852]: info: Starting UPNP Browser Sep 01 01:12:53 stefans-speaker volumio[13852]: info: Loading plugin "alarm-clock"... Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Loading plugin "airplay_emulation"... Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Starting Shairport Sync Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Loading plugin "last_100"... Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Loading plugin "webradio"... Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Loading plugin "i2s_dacs"... Sep 01 01:12:54 stefans-speaker volumio[13852]: info: I2S DAC not set, start Auto-detection Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Loading plugin "volumiodiscovery"... Sep 01 01:12:54 stefans-speaker volumio[13852]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:54 stefans-speaker volumio[13852]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:54 stefans-speaker volumio[13852]: *** WARNING *** For more information see Sep 01 01:12:54 stefans-speaker volumio[13852]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:54 stefans-speaker volumio[13852]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:54 stefans-speaker volumio[13852]: *** WARNING *** For more information see Sep 01 01:12:54 stefans-speaker node[13852]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:54 stefans-speaker node[13852]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:54 stefans-speaker node[13852]: *** WARNING *** For more information see Sep 01 01:12:54 stefans-speaker node[13852]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:12:54 stefans-speaker node[13852]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:12:54 stefans-speaker node[13852]: *** WARNING *** For more information see Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:12:54 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:12:54 stefans-speaker volumio[13852]: info: Loading plugin "spop"... Sep 01 01:12:55 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:55+02:00" level=debug msg="obtained new client token: AAGvVkds1aEmdn0MXtixguHS3LacLOVxarMOycpaRyalxXGfmaHsNnjAeCZmWJxaBW13EkSYz3YSaEu5nSmMDYxpY38bVAyUtBW0rQyFONEeDHI2tpfvg2ubj1leFZMutZM1hO2jr9SVkNtGyKH86FwsZv2AdsxyWddbHoG2gmqk60rdLXJa9fY7oP0EhVAuHPfp9f+RfsfR3gUGWUnNkf7GjZEQvQG/LxAzgxy7PzKS3t2m11gSn8GE" Sep 01 01:12:55 stefans-speaker sudo[13898]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:55 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:12:55 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:55+02:00" level=debug msg="completed keyexchange" Sep 01 01:12:55 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:55+02:00" level=debug msg="completed challenge" Sep 01 01:12:55 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12:55+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:12:55 stefans-speaker volumio[13852]: info: Loading plugin "ytcr"... Sep 01 01:12:55 stefans-speaker go-librespot[13868]: time="2026-09-01T01:12: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 01:12:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:12:55 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:12:56 stefans-speaker volumio[13852]: info: Loading plugin "outputs"... Sep 01 01:12:56 stefans-speaker volumio[13852]: info: Loading plugin "albumart"... Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Plugin example_plugin is not enabled Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Loading plugin "inputs"... Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Loading plugin "updater_comm"... Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Plugin mpdemulation is not enabled Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Loading plugin "rest_api"... Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Loading plugin "websocket"... Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Loading i18n strings for locale nl Sep 01 01:12:57 stefans-speaker volumio[13852]: Updating browse sources language Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::initPlayerControls Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: Express server listening on port 3000 Sep 01 01:12:57 stefans-speaker volumio[13852]: [Metrics] WebUI: 8s 117.37ms Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::resetVolumioState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::getcurrentVolume Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Cannot read play queue from file Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:12:57 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:12:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788217972 101 Sep 01 01:12:57 stefans-speaker volumio[13924]: Forking 3 albumart workers Sep 01 01:12:57 stefans-speaker volumio[13852]: 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: 1 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::pushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::updateTrackBlock Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrackBlock Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::pushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::setRandom null Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::pushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Setting Device type: Raspberry PI Sep 01 01:12:57 stefans-speaker volumio[13852]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreStateMachine::pushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioPushState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioGetState Sep 01 01:12:57 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:12:57 stefans-speaker volumio[13852]: info: Completed loading Core Plugins Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Preparing to generate the ALSA configuration file Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Output device has changed, restarting MPD Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Output device has changed, restarting Shairport Sync Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:58 stefans-speaker sudo[13978]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:58 stefans-speaker sudo[13980]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:58 stefans-speaker sudo[13978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:12:58 stefans-speaker volumio[13852]: info: ___________ START PLUGINS ___________ Sep 01 01:12:58 stefans-speaker sudo[13978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:58 stefans-speaker sudo[13978]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:58 stefans-speaker sudo[13980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:12:58 stefans-speaker sudo[13980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:58 stefans-speaker volumio[13852]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Creating MPD Configuration file Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:58 stefans-speaker volumio[13852]: info: [1788217978176] CoreMusicLibrary::Adding element Media Servers Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:58 stefans-speaker volumio[13852]: info: UPNP Browser: Client initialized successfully Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:58 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:58 stefans-speaker sudo[13989]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:58 stefans-speaker sudo[13989]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:12:58 stefans-speaker sudo[13989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:58 stefans-speaker sudo[13989]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:58 stefans-speaker sudo[13990]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:12:58 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:12:58 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:12:58 stefans-speaker systemd[1]: mpd.service: Consumed 4.553s CPU time. Sep 01 01:12:58 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:12:58 stefans-speaker sudo[13990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:12:58 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:12:58 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:12:58 stefans-speaker sudo[13990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:58 stefans-speaker volumio[13852]: info: [1788217978340] CoreMusicLibrary::Adding element Last_100 Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:12:58 stefans-speaker volumio[13852]: info: [1788217978350] CoreMusicLibrary::Adding element Webradio Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Initializing BBC Radios Sep 01 01:12:58 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:12:58 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:12:58 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:12:58 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:12:58 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:12:58 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:12:58 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:12:58 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:12:58 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:12:58 stefans-speaker volumio[13852]: info: Creating Spotify config file Sep 01 01:12:58 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:12:58 stefans-speaker sudo[14012]: root : unable to resolve host stefans-speaker: System error Sep 01 01:12:58 stefans-speaker sudo[14012]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:12:58 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 01 01:12:58 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:58 stefans-speaker sudo[14012]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:12:58 stefans-speaker sudo[14012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:12:58 stefans-speaker sudo[14012]: pam_unix(sudo:session): session closed for user root Sep 01 01:12:58 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:12:58 stefans-speaker go-librespot[14019]: go-librespot daemon starting... Sep 01 01:12:58 stefans-speaker go-librespot[14020]: time="2026-09-01T01:12:58+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:12:59 stefans-speaker go-librespot[14020]: time="2026-09-01T01:12:59+02:00" level=info msg="zeroconf server listening on port 37121" Sep 01 01:12:59 stefans-speaker volumio[13939]: Starting albumart workers Sep 01 01:12:59 stefans-speaker volumio[13852]: info: Volumio Calling Home Sep 01 01:12:59 stefans-speaker volumio[13941]: Starting albumart workers Sep 01 01:12:59 stefans-speaker volumio[13852]: info: MPD Permissions set Sep 01 01:12:59 stefans-speaker volumio[13852]: info: MPD Permissions set Sep 01 01:13:00 stefans-speaker volumio[13852]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 01 01:13:00 stefans-speaker volumio[13852]: info: Spotify config file written Sep 01 01:13:00 stefans-speaker volumio[13940]: Starting albumart workers Sep 01 01:13:00 stefans-speaker sudo[14032]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:00 stefans-speaker sudo[14032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:13:00 stefans-speaker sudo[14032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:00 stefans-speaker volumio[13852]: info: Volumio called home Sep 01 01:13:00 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 01:13:00 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 01:13:00 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:00 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:00 stefans-speaker go-librespot[14034]: go-librespot daemon starting... Sep 01 01:13:00 stefans-speaker sudo[14032]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+02:00" level=debug msg="app state loaded" Sep 01 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+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 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+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 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+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 01:13:00 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:00+02:00" level=info msg="zeroconf server listening on port 36959" Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:00 stefans-speaker volumio[13852]: info: No need to fix Spotify hosts Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Starting Shairport Sync Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Starting Shairport Sync Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Starting Shairport Sync Sep 01 01:13:01 stefans-speaker sudo[14075]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:01 stefans-speaker sudo[14074]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:01 stefans-speaker sudo[14075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:13:01 stefans-speaker sudo[14075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:01 stefans-speaker sudo[14074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:13:01 stefans-speaker volumio[13852]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:13:01 stefans-speaker volumio[13852]: SPOTIFY: BQAJfHGMDXBqmACfYl0RvS_-GeWnmYqcT-Cf8gz4wcXsWmB9FtjFDm4UwJ9BYn2hmZzFUULLT6RCZIlAWxxeQ3fY99KsdHb3FC3qOQhcBd_8jM73rO7KMeKUyo-QxKlHcS2lfhF_TxfKSJk_tI3-7g5SsGZ7dOTI8gn8K_I0UFoE08BXRmy33JWsIMAbW5-amG9KpK3MprnCjseEEszyhQtrV1UwiNLnxKBKgLglvmOijGBMN1DQInACpP47pekX0TEtb-hCbKFnQyTc4pMvsz8vGAsHHCVBkMLdsJOGa9pYwphyzvsAQd5SdOyEDpY Sep 01 01:13:01 stefans-speaker volumio[13852]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:13:01 stefans-speaker volumio[13852]: info: New Spotify access token = BQAJfHGMDXBqmACfYl0RvS_-GeWnmYqcT-Cf8gz4wcXsWmB9FtjFDm4UwJ9BYn2hmZzFUULLT6RCZIlAWxxeQ3fY99KsdHb3FC3qOQhcBd_8jM73rO7KMeKUyo-QxKlHcS2lfhF_TxfKSJk_tI3-7g5SsGZ7dOTI8gn8K_I0UFoE08BXRmy33JWsIMAbW5-amG9KpK3MprnCjseEEszyhQtrV1UwiNLnxKBKgLglvmOijGBMN1DQInACpP47pekX0TEtb-hCbKFnQyTc4pMvsz8vGAsHHCVBkMLdsJOGa9pYwphyzvsAQd5SdOyEDpY Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:13:01 stefans-speaker sudo[14074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:01 stefans-speaker sudo[14077]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:01 stefans-speaker sudo[14077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:13:01 stefans-speaker sudo[14077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:01 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:13:01 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:13:01 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:13:01 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.907s CPU time. Sep 01 01:13:01 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:13:01 stefans-speaker sudo[14075]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:01 stefans-speaker sudo[14074]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:01 stefans-speaker sudo[14077]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Shairport-Sync Started Sep 01 01:13:01 stefans-speaker volumio[13852]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Shairport-Sync Started Sep 01 01:13:01 stefans-speaker volumio[13852]: info: Shairport-Sync Started Sep 01 01:13:01 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:01 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:01 stefans-speaker volumio[13852]: 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 01:13:01 stefans-speaker volumio[13852]: info: Spotify Successfully logged in Sep 01 01:13:01 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:13:01 stefans-speaker volumio[13852]: info: [1788217981730] CoreMusicLibrary::Adding element Spotify Sep 01 01:13:01 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:13:01 stefans-speaker volumio[13852]: Cannot find translation for source Spotify Sep 01 01:13:02 stefans-speaker sudo[14111]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:02 stefans-speaker sudo[14111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:13:02 stefans-speaker sudo[14111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:02 stefans-speaker sudo[14111]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:02 stefans-speaker sudo[14113]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:02 stefans-speaker volumio[13852]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:13:02 stefans-speaker sudo[14113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:13:02 stefans-speaker sudo[14113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:02 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:13:02 stefans-speaker sudo[14113]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:02 stefans-speaker volumio[13852]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:13:02 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:02 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:02 stefans-speaker volumio[13852]: info: CoreStateMachine::pushState Sep 01 01:13:02 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:02 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioPushState Sep 01 01:13:02 stefans-speaker sudo[14119]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:02 stefans-speaker sudo[14119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:13:02 stefans-speaker sudo[14119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:02 stefans-speaker sudo[14119]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:02 stefans-speaker volumio[13852]: info: Upmpdcli Daemon Started Sep 01 01:13:03 stefans-speaker mpd[14021]: 2026-09-01T01:13:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:13:03 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:13:03 stefans-speaker sudo[13980]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:03 stefans-speaker sudo[13990]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:03 stefans-speaker volumio[13852]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:13:03 stefans-speaker volumio[13852]: assert.ok(self.idling) Sep 01 01:13:03 stefans-speaker volumio[13852]: error: The expression evaluated to a falsy value: Sep 01 01:13:03 stefans-speaker volumio[13852]: assert.ok(self.idling) Sep 01 01:13:03 stefans-speaker volumio[13852]: error: updateQueue error: null Sep 01 01:13:03 stefans-speaker volumio[13852]: info: MPD running with PID14021 Sep 01 01:13:03 stefans-speaker volumio[13852]: ,establishing connection Sep 01 01:13:03 stefans-speaker volumio[13852]: info: Completed starting Core Plugins Sep 01 01:13:03 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:13:03 stefans-speaker volumio[13852]: info: ----- MyVolumio plugins startup ---- Sep 01 01:13:03 stefans-speaker volumio[13852]: info: ------------------------------------------- Sep 01 01:13:03 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:13:03 stefans-speaker volumio[13852]: error: updateQueue error: null Sep 01 01:13:04 stefans-speaker volumio[13852]: info: go-librespot daemon successfully initialized Sep 01 01:13:06 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:06+02:00" level=debug msg="obtained new client token: AAEG8F8+OkiHwqWhSyrOnj5es6d32INVUydGMn2ezfM9FyM0FQ/2lEkFWCauOg/3vIyWYwe/vTzTVcqnNWt2I/RleSKaoR7hGq4Ngbk+M41ya6frBS4MxJo7YGXlKeQcQvSi+lJrPbz2U1qkSeYLt3WGgAbiy6f39z3/FLfMsrDbm53netNx9t2kArB7rmDM3hbL2okGiIpYP3FxnCjZSxZyyCJgFCvgDbBj4Se79C7pDeAKxt3WdWXk" Sep 01 01:13:06 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:06 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:06+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:06 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:06+02:00" level=debug msg="completed challenge" Sep 01 01:13:06 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:06+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:06 stefans-speaker sudo[14130]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:06 stefans-speaker sudo[14130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:13:06 stefans-speaker sudo[14130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:06 stefans-speaker sudo[14132]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:06 stefans-speaker go-librespot[14035]: time="2026-09-01T01:13:06+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 01:13:06 stefans-speaker sudo[14132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:13:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:06 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:06 stefans-speaker sudo[14132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:06 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:13:06 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:13:06 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:13:06 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:13:06 stefans-speaker sudo[14130]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:06 stefans-speaker sudo[14132]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:06 stefans-speaker mpd_monitor.sh[14135]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:13:06 stefans-speaker volumio[13852]: info: Successfully started MPD Monitor Sep 01 01:13:06 stefans-speaker volumio[13852]: info: Successfully started MPD Monitor Sep 01 01:13:07 stefans-speaker volumio[13852]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:07 stefans-speaker volumio[13852]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:13:08 stefans-speaker volumio[13852]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:13:09 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 01:13:09 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:09 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:09 stefans-speaker go-librespot[14139]: go-librespot daemon starting... Sep 01 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:09+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:09+02:00" level=debug msg="app state loaded" Sep 01 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13: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 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13: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 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13: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 01:13:09 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:09+02:00" level=info msg="zeroconf server listening on port 35461" Sep 01 01:13:10 stefans-speaker volumio[13852]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:10 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:10+02:00" level=debug msg="new websocket client" Sep 01 01:13:10 stefans-speaker volumio[13852]: info: Connection to go-librespot Websocket established Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:13:11 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:13:12 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:13:12 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:13:12 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:12 stefans-speaker volumio[13852]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:12 stefans-speaker volumio[13852]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:13:12 stefans-speaker volumio[13852]: info: MyVolumio login type: Token Sep 01 01:13:12 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:13:12 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:13:13 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:13:13 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:13:13 stefans-speaker volumio[13852]: info: Streaming services startup Sep 01 01:13:13 stefans-speaker volumio[13852]: info: Starting Streaming Daemon Sep 01 01:13:14 stefans-speaker sudo[14162]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:14 stefans-speaker volumio[13852]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:13:14 stefans-speaker sudo[14162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:13:14 stefans-speaker sudo[14162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:14 stefans-speaker sudo[14162]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:14 stefans-speaker volumio[13852]: info: Getting Spotify volume Sep 01 01:13:14 stefans-speaker volumio[13852]: error: Cannot start Volumio Streaming Daemon Sep 01 01:13:14 stefans-speaker volumio[13852]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:13:14 stefans-speaker volumio[13852]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:13:14 stefans-speaker volumio[13852]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:13:14 stefans-speaker volumio[13852]: 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 01:13:14 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:14 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:14 stefans-speaker volumio[13852]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Sep 01 01:13:14 stefans-speaker volumio[13852]: SPOTIFY: SPOTIFY VOLUME undefined Sep 01 01:13:14 stefans-speaker volumio[13852]: SPOTIFY: VOLUMIO VOLUME 70 Sep 01 01:13:14 stefans-speaker volumio[13852]: info: Aligning Spotify Volume to Volumio Volume Sep 01 01:13:14 stefans-speaker volumio[13852]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:14 stefans-speaker volumio[13852]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:14 stefans-speaker volumio[13852]: info: Setting Spotify Volume from Volumio: 70 Sep 01 01:13:14 stefans-speaker volumio[13852]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:13:14 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:14+02:00" level=debug msg="obtained new client token: AAEdPiHv3QRn80TfTwXi+vvljHEMQXCrOJAd0Z36arg2+C/Pj1H1jC5ju2bXzu2qbWKyFNvAl19T/RsGYmfHyRKx7D9slxu0x+4qP1HmQVd+6UPNPaZ3NEmgFilkkaYh5ezSLVQn7Z/jXABz9JNWHEIurugV4m1BDcJO4T2WJKeW4PJc5pwRz4CuB075Zqk0ZcWkKFbxxqoqhNxmbFeNda6i/QsfxpFxIFkPTxzPoPzMx3bV8Wf+ZGDXOOQ=" Sep 01 01:13:15 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:15 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:15+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:15 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:15+02:00" level=debug msg="completed challenge" Sep 01 01:13:15 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:15+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:15 stefans-speaker volumio[13852]: info: MyVolumio token set successfully Sep 01 01:13:15 stefans-speaker volumio[13852]: info: MYVOLUMIO: Adding device Sep 01 01:13:15 stefans-speaker volumio[13852]: info: MYVOLUMIO: Evaluating Server Sep 01 01:13:15 stefans-speaker go-librespot[14140]: time="2026-09-01T01:13:15+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 01:13:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:15 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:15 stefans-speaker volumio[13852]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:13:15 stefans-speaker volumio[13852]: Error: socket hang up Sep 01 01:13:15 stefans-speaker volumio[13852]: at connResetException (node:internal/errors:720:14) Sep 01 01:13:15 stefans-speaker volumio[13852]: at Socket.socketOnEnd (node:_http_client:519:23) Sep 01 01:13:15 stefans-speaker volumio[13852]: at Socket.emit (node:events:526:35) Sep 01 01:13:15 stefans-speaker volumio[13852]: at endReadableNT (node:internal/streams/readable:1376:12) Sep 01 01:13:15 stefans-speaker volumio[13852]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Sep 01 01:13:15 stefans-speaker volumio[13852]: code: 'ECONNRESET', Sep 01 01:13:15 stefans-speaker volumio[13852]: response: undefined Sep 01 01:13:15 stefans-speaker volumio[13852]: } Sep 01 01:13:15 stefans-speaker volumio[13852]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:13:16 stefans-speaker sudo[14184]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:16 stefans-speaker sudo[14184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:12' Sep 01 01:13:16 stefans-speaker sudo[14184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:16 stefans-speaker sudo[14184]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:16 stefans-speaker volumio[13852]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:13:16 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:13:16] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 01 01:13:16 stefans-speaker systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:16 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:13:16] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 01 01:13:16 stefans-speaker systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 01 01:13:16 stefans-speaker systemd[1]: volumio.service: Consumed 31.498s CPU time. Sep 01 01:13:16 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:13:16 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:13:16 stefans-speaker systemd[1]: volumio.service: Scheduled restart job, restart counter is at 303. Sep 01 01:13:16 stefans-speaker systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 01 01:13:16 stefans-speaker systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 01 01:13:16 stefans-speaker systemd[1]: volumio.service: Consumed 31.498s CPU time. Sep 01 01:13:16 stefans-speaker systemd[1]: Started volumio.service - Volumio Backend Module. Sep 01 01:13:16 stefans-speaker systemd[1]: dynamicswap.service: Deactivated successfully. Sep 01 01:13:18 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 01:13:18 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:18 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:18 stefans-speaker go-librespot[14222]: go-librespot daemon starting... Sep 01 01:13:18 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:18+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:18+02:00" level=debug msg="app state loaded" Sep 01 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:18+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:18 stefans-speaker volumio[14201]: info: ----- Volumio3 ---- Sep 01 01:13:18 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:18 stefans-speaker volumio[14201]: info: ----- System startup ---- Sep 01 01:13:18 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Sep 01 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:18+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 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:18+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 01:13:18 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:18+02:00" level=info msg="zeroconf server listening on port 33449" Sep 01 01:13:19 stefans-speaker volumio[14201]: info: MYVOLUMIO Environment detected Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Plugin folders cleanup Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning into folder /volumio/app/plugins/ Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category audio_interface Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category miscellanea Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category music_service Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category plugins.json Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category system_controller Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category user_interface Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning into folder /data/plugins/ Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Scanning category music_service Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Plugin folders cleanup completed Sep 01 01:13:19 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:19 stefans-speaker volumio[14201]: info: ----- Core plugins startup ---- Sep 01 01:13:19 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Loading plugins from folder /volumio/app/plugins/ Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Adding plugin upnp to MyMusic Plugins Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Loading plugins from folder /data/plugins/ Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Loading plugin "system"... Sep 01 01:13:19 stefans-speaker volumio[14201]: info: Loading plugin "appearance"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "network"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Refreshing Cached IP Addresses Sep 01 01:13:20 stefans-speaker sudo[14237]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:20 stefans-speaker sudo[14239]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "services"... Sep 01 01:13:20 stefans-speaker sudo[14237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:13:20 stefans-speaker sudo[14239]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:13:20 stefans-speaker sudo[14237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:20 stefans-speaker sudo[14239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "alsa_controller"... Sep 01 01:13:20 stefans-speaker sudo[14237]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:20 stefans-speaker sudo[14239]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:20 stefans-speaker sudo[14249]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:20 stefans-speaker sudo[14249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 01 01:13:20 stefans-speaker sudo[14249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:20 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "wizard"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "networkfs"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Starting Udev Watcher for removable devices Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Ignoring mount for partition: boot Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Ignoring mount for partition: volumio Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Ignoring mount for partition: volumio_data Sep 01 01:13:20 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "volumio_command_line_client"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "upnp"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: [1788218000940] Starting Upmpd Daemon Sep 01 01:13:20 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "my_music"... Sep 01 01:13:20 stefans-speaker volumio[14201]: info: Loading plugin "mpd"... Sep 01 01:13:21 stefans-speaker volumio[14201]: info: Loading plugin "upnp_browser"... Sep 01 01:13:21 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:13:21] [connect] Successful connection Sep 01 01:13:22 stefans-speaker volumio[14201]: info: Starting UPNP Browser Sep 01 01:13:22 stefans-speaker volumio[14201]: info: Loading plugin "alarm-clock"... Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Loading plugin "airplay_emulation"... Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Starting Shairport Sync Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Loading plugin "last_100"... Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Loading plugin "webradio"... Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Loading plugin "i2s_dacs"... Sep 01 01:13:23 stefans-speaker volumio[14201]: info: I2S DAC not set, start Auto-detection Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Loading plugin "volumiodiscovery"... Sep 01 01:13:23 stefans-speaker volumio[14201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:13:23 stefans-speaker volumio[14201]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:13:23 stefans-speaker volumio[14201]: *** WARNING *** For more information see Sep 01 01:13:23 stefans-speaker volumio[14201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:13:23 stefans-speaker volumio[14201]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:13:23 stefans-speaker volumio[14201]: *** WARNING *** For more information see Sep 01 01:13:23 stefans-speaker node[14201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 01:13:23 stefans-speaker node[14201]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:13:23 stefans-speaker node[14201]: *** WARNING *** For more information see Sep 01 01:13:23 stefans-speaker node[14201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 01:13:23 stefans-speaker node[14201]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 01:13:23 stefans-speaker node[14201]: *** WARNING *** For more information see Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Discovery: Started advertising with name: Stefan’s Speaker Sep 01 01:13:23 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 01:13:23 stefans-speaker volumio[14201]: info: Loading plugin "spop"... Sep 01 01:13:24 stefans-speaker sudo[14249]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:24 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:24+02:00" level=debug msg="obtained new client token: AAFB6gWhv9qWeCcsY9dP8o1nemHa819+Fw6vs71MsUUTg/YJcOJy2oYw2tds2A4ONPtXdjs6QggWXod2lKTYjMyAGyocSJj93k7W2AxZn+oBmfZ9g5UkRTJqE8DDK5wcJnHjO8g/enllY+DybmAW8C/fxUyFd3noVIzTL2cqgrVPDRkhNea2gbwE69LNMrQfwN3mjOzmKvQrOuNNHHVFuPh20g062RlyKHDnI0xJQML13LljrxjjU4Hs" Sep 01 01:13:24 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:24 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:24+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:24 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:24+02:00" level=debug msg="completed challenge" Sep 01 01:13:24 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13:24+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:24 stefans-speaker volumio[14201]: info: Loading plugin "ytcr"... Sep 01 01:13:24 stefans-speaker go-librespot[14223]: time="2026-09-01T01:13: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 01:13:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:24 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Loading plugin "outputs"... Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Loading plugin "albumart"... Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Plugin example_plugin is not enabled Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Loading plugin "inputs"... Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Loading plugin "updater_comm"... Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Plugin mpdemulation is not enabled Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Loading plugin "rest_api"... Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Loading plugin "websocket"... Sep 01 01:13:25 stefans-speaker volumio[14201]: info: Starting Socket.io Server version 1.7.4 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Loading i18n strings for locale nl Sep 01 01:13:26 stefans-speaker volumio[14201]: Updating browse sources language Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::initPlayerControls Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: Express server listening on port 3000 Sep 01 01:13:26 stefans-speaker volumio[14201]: [Metrics] WebUI: 8s 88.77ms Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::resetVolumioState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::getcurrentVolume Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Cannot read play queue from file Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Volumio Network Manager: Network status updated: 2 Sep 01 01:13:26 stefans-speaker volumio[14274]: Forking 3 albumart workers Sep 01 01:13:26 stefans-speaker volumio[14201]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::pushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioPushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::updateTrackBlock Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrackBlock Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::setRepeat null single undefined Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::pushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioPushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::setRandom null Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::pushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioPushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Setting Device type: Raspberry PI Sep 01 01:13:26 stefans-speaker volumio[14201]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreStateMachine::pushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioPushState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Completed loading Core Plugins Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Preparing to generate the ALSA configuration file Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Discovery: adding a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Discovery: this is already registered, a312d0fa-c0eb-45ad-a25b-79fcdf237e8a Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Discovery: Found device Stefan’s Speaker Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Asound.conf file unchanged, so no further update is needed Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Output device has changed, restarting MPD Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Output device has changed, restarting Shairport Sync Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:26 stefans-speaker sudo[14332]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:13:26 stefans-speaker sudo[14334]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:26 stefans-speaker sudo[14334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:13:26 stefans-speaker volumio[14201]: info: ___________ START PLUGINS ___________ Sep 01 01:13:26 stefans-speaker sudo[14334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:26 stefans-speaker sudo[14332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:13:26 stefans-speaker sudo[14332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:26 stefans-speaker sudo[14332]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:26 stefans-speaker volumio[14201]: info: ControllerMpd::onStart: Initializing MPD Sep 01 01:13:26 stefans-speaker volumio[14201]: info: Creating MPD Configuration file Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:13:26 stefans-speaker volumio[14201]: info: [1788218006990] CoreMusicLibrary::Adding element Media Servers Sep 01 01:13:26 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:13:27 stefans-speaker volumio[14201]: info: UPNP Browser: Client initialized successfully Sep 01 01:13:27 stefans-speaker systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 01 01:13:27 stefans-speaker sudo[14344]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:27 stefans-speaker sudo[14342]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:27 stefans-speaker sudo[14344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 01:13:27 stefans-speaker sudo[14344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:27 stefans-speaker sudo[14342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 01:13:27 stefans-speaker sudo[14342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:27 stefans-speaker sudo[14342]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:27 stefans-speaker volumio[14201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:27 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:13:27 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:13:27 stefans-speaker systemd[1]: mpd.service: Consumed 4.473s CPU time. Sep 01 01:13:27 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:13:27 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:13:27 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:13:27 stefans-speaker volumio[14201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:13:27 stefans-speaker volumio[14201]: info: [1788218007159] CoreMusicLibrary::Adding element Last_100 Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:13:27 stefans-speaker volumio[14201]: info: [1788218007173] CoreMusicLibrary::Adding element Webradio Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:13:27 stefans-speaker volumio[14201]: info: Initializing BBC Radios Sep 01 01:13:27 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:13:27 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:13:27 stefans-speaker systemd[1]: mpd.service: Deactivated successfully. Sep 01 01:13:27 stefans-speaker systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 01 01:13:27 stefans-speaker systemd[1]: mpd.socket: Deactivated successfully. Sep 01 01:13:27 stefans-speaker systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 01 01:13:27 stefans-speaker systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 01 01:13:27 stefans-speaker systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 01 01:13:27 stefans-speaker systemd[1]: Starting mpd.service - Music Player Daemon... Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:27 stefans-speaker volumio[14201]: info: Creating Spotify config file Sep 01 01:13:27 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:27 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 01:13:27 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:27 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:27 stefans-speaker go-librespot[14372]: go-librespot daemon starting... Sep 01 01:13:27 stefans-speaker sudo[14369]: root : unable to resolve host stefans-speaker: System error Sep 01 01:13:27 stefans-speaker sudo[14369]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:13:27 stefans-speaker sudo[14369]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 01:13:27 stefans-speaker sudo[14369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 01 01:13:27 stefans-speaker sudo[14369]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:27 stefans-speaker go-librespot[14373]: time="2026-09-01T01:13:27+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:27 stefans-speaker volumio[14201]: info: Volumio Calling Home Sep 01 01:13:28 stefans-speaker go-librespot[14373]: time="2026-09-01T01:13:28+02:00" level=info msg="zeroconf server listening on port 43259" Sep 01 01:13:28 stefans-speaker volumio[14290]: Starting albumart workers Sep 01 01:13:28 stefans-speaker volumio[14291]: Starting albumart workers Sep 01 01:13:28 stefans-speaker volumio[14201]: info: MPD Permissions set Sep 01 01:13:28 stefans-speaker volumio[14201]: info: MPD Permissions set Sep 01 01:13:28 stefans-speaker volumio[14201]: info: Spotify config file written Sep 01 01:13:28 stefans-speaker volumio[14201]: 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 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker sudo[14388]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker sudo[14388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 01 01:13:28 stefans-speaker sudo[14388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 01:13:28 stefans-speaker volumio[14201]: info: No need to fix Spotify hosts Sep 01 01:13:28 stefans-speaker systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 01 01:13:28 stefans-speaker systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 01 01:13:28 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:29 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:29 stefans-speaker go-librespot[14400]: go-librespot daemon starting... Sep 01 01:13:29 stefans-speaker sudo[14388]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=debug msg="app state loaded" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:29 stefans-speaker volumio[14292]: Starting albumart workers Sep 01 01:13:29 stefans-speaker volumio[14201]: info: Volumio called home Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+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 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+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 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+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 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=info msg="zeroconf server listening on port 35633" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=debug msg="obtained new client token: AAEeZaB5p/f9kPE6/POptDUZngTADBhQfzCitsbRsjfPYCODayRjHFr2cpvyKeIF3VOhoGs4ACWiXh2CUJ5liKCZpxXCLl/WwgyDHNvCXO6ydZHv9nmt6mNAkjOc1pUl0/g54MxRRef6q9sw86CQQDZOWGmEUhpqkj2fvbF4w9W9PArfVoSIS+GsJqx9CzRlSdNkw7cGPMST3nEQsmLwPZUOso7VV6JyKdOVsygqwhzkbwkfDfaEAzLi0bU=" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=debug msg="completed challenge" Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:29 stefans-speaker volumio[14201]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:13:29 stefans-speaker volumio[14201]: SPOTIFY: BQCBr2RUEWEcmpbqmMyZ0Yu007lsvesVv6xmA_GF9uPJyH59EWr-j-eUAu2Dyd_aHhHK4ejheklvje3QyIcji-nVoH0hIJ7dtskoICUqdYydrph8xzxnnTXJ5N9PxWz05XmrJr5j_OdHkE5Jaqog6liiINltUeLAQusWcqDdgsAbCUivKm175kepsTB5fmRy4nuGXfUDCIv1cy8dX44JW0QWM_zxNp2ZbHtdY9kNJR1vsZmeQUf2pL8Gz35FMpewKsC-Jzb9Emc3irW4CnGf2-L7QtETJ3XCNAGId6YndFMe7gpGUC8hrGldAm8EHMo Sep 01 01:13:29 stefans-speaker volumio[14201]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 01 01:13:29 stefans-speaker volumio[14201]: info: New Spotify access token = BQCBr2RUEWEcmpbqmMyZ0Yu007lsvesVv6xmA_GF9uPJyH59EWr-j-eUAu2Dyd_aHhHK4ejheklvje3QyIcji-nVoH0hIJ7dtskoICUqdYydrph8xzxnnTXJ5N9PxWz05XmrJr5j_OdHkE5Jaqog6liiINltUeLAQusWcqDdgsAbCUivKm175kepsTB5fmRy4nuGXfUDCIv1cy8dX44JW0QWM_zxNp2ZbHtdY9kNJR1vsZmeQUf2pL8Gz35FMpewKsC-Jzb9Emc3irW4CnGf2-L7QtETJ3XCNAGId6YndFMe7gpGUC8hrGldAm8EHMo Sep 01 01:13:29 stefans-speaker volumio[14201]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 01 01:13:29 stefans-speaker go-librespot[14401]: time="2026-09-01T01:13:29+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 01:13:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:29 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:30 stefans-speaker volumio[14201]: info: Starting Shairport Sync Sep 01 01:13:30 stefans-speaker volumio[14201]: info: Starting Shairport Sync Sep 01 01:13:30 stefans-speaker volumio[14201]: info: Starting Shairport Sync Sep 01 01:13:30 stefans-speaker sudo[14421]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:30 stefans-speaker sudo[14423]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:30 stefans-speaker sudo[14421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:13:30 stefans-speaker sudo[14421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:30 stefans-speaker sudo[14423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:13:30 stefans-speaker sudo[14423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:30 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:13:30 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:13:30 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:13:30 stefans-speaker systemd[1]: shairport-sync.service: Consumed 1.797s CPU time. Sep 01 01:13:30 stefans-speaker sudo[14427]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:30 stefans-speaker sudo[14427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 01 01:13:30 stefans-speaker sudo[14427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:30 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:13:30 stefans-speaker systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 01 01:13:30 stefans-speaker systemd[1]: shairport-sync.service: Deactivated successfully. Sep 01 01:13:30 stefans-speaker systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:13:30 stefans-speaker sudo[14421]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:30 stefans-speaker systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 01 01:13:30 stefans-speaker sudo[14423]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:30 stefans-speaker sudo[14427]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:30 stefans-speaker volumio[14201]: info: Shairport-Sync Started Sep 01 01:13:30 stefans-speaker volumio[14201]: Error adding Membership: Error: addMembership EINVAL Sep 01 01:13:30 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:30 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:30 stefans-speaker volumio[14201]: info: Shairport-Sync Started Sep 01 01:13:30 stefans-speaker volumio[14201]: info: Shairport-Sync Started Sep 01 01:13:30 stefans-speaker volumio[14201]: 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 01:13:30 stefans-speaker volumio[14201]: info: Spotify Successfully logged in Sep 01 01:13:30 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 01:13:30 stefans-speaker volumio[14201]: info: [1788218010702] CoreMusicLibrary::Adding element Spotify Sep 01 01:13:30 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 01:13:30 stefans-speaker volumio[14201]: Cannot find translation for source Spotify Sep 01 01:13:31 stefans-speaker sudo[14467]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:31 stefans-speaker sudo[14467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 01:13:31 stefans-speaker sudo[14467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:31 stefans-speaker sudo[14469]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:31 stefans-speaker sudo[14467]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:31 stefans-speaker sudo[14469]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 01:13:31 stefans-speaker sudo[14469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:31 stefans-speaker sudo[14469]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:31 stefans-speaker volumio[14201]: info: [yt-cast-receiver] DIAL server listening on port 8098 Sep 01 01:13:31 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 01:13:31 stefans-speaker sudo[14473]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:31 stefans-speaker sudo[14473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 01 01:13:31 stefans-speaker sudo[14473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:31 stefans-speaker sudo[14473]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:31 stefans-speaker volumio[14201]: info: Upmpdcli Daemon Started Sep 01 01:13:31 stefans-speaker volumio[14201]: info: VolumeController:: Volume=70 Mute =false Sep 01 01:13:31 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioGetState Sep 01 01:13:31 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:31 stefans-speaker volumio[14201]: info: CoreStateMachine::pushState Sep 01 01:13:31 stefans-speaker volumio[14201]: info: CorePlayQueue::getTrack 0 Sep 01 01:13:31 stefans-speaker volumio[14201]: info: CoreCommandRouter::volumioPushState Sep 01 01:13:32 stefans-speaker mpd[14380]: 2026-09-01T01:13:32 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 01:13:32 stefans-speaker systemd[1]: Started mpd.service - Music Player Daemon. Sep 01 01:13:32 stefans-speaker sudo[14344]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:32 stefans-speaker sudo[14334]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:32 stefans-speaker volumio[14201]: error: MPD error: The expression evaluated to a falsy value: Sep 01 01:13:32 stefans-speaker volumio[14201]: assert.ok(self.idling) Sep 01 01:13:32 stefans-speaker volumio[14201]: error: The expression evaluated to a falsy value: Sep 01 01:13:32 stefans-speaker volumio[14201]: assert.ok(self.idling) Sep 01 01:13:32 stefans-speaker volumio[14201]: info: MPD running with PID14380 Sep 01 01:13:32 stefans-speaker volumio[14201]: ,establishing connection Sep 01 01:13:32 stefans-speaker volumio[14201]: error: updateQueue error: null Sep 01 01:13:32 stefans-speaker volumio[14201]: info: Completed starting Core Plugins Sep 01 01:13:32 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:32 stefans-speaker volumio[14201]: info: ----- MyVolumio plugins startup ---- Sep 01 01:13:32 stefans-speaker volumio[14201]: info: ------------------------------------------- Sep 01 01:13:32 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 01:13:32 stefans-speaker volumio[14201]: error: updateQueue error: null Sep 01 01:13:32 stefans-speaker volumio[14201]: info: go-librespot daemon successfully initialized Sep 01 01:13:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 01 01:13:33 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:33 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:33 stefans-speaker go-librespot[14483]: go-librespot daemon starting... Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=debug msg="app state loaded" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+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 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+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 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+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 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=info msg="zeroconf server listening on port 38611" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=debug msg="obtained new client token: AAFiHILKXh3X7MpqRYfyjhnqVByMrf7P3F2cvwYLFJDkoiO1lxlNK35CmtEZaviyIPlJmAwmYFuzWnRb0LVNo1dOnxLUrjevAYr7y0tsmv4u0pqkR4SCbnkFgqp1d09B+gPzFg0yKlUmxJoLWxK2NEhXPzPN2rn1pyuuW+D1nIhHKwOY/cyvuvhwgW7QwIHcxA1oWwt0a/j2z0K1iXjErDJuNO7hW+rR9lUsHV1LAPJKqhBMZ6RmBzuXhH0=" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+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 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=debug msg="completed challenge" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:33 stefans-speaker go-librespot[14484]: time="2026-09-01T01:13:33+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 01:13:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:33 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:33 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:13:33] [connect] Successful connection Sep 01 01:13:33 stefans-speaker volumio-remote-updater[679]: [2026-09-01 01:13:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1788218013 101 Sep 01 01:13:33 stefans-speaker volumio[14201]: 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 01:13:35 stefans-speaker sudo[14494]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:35 stefans-speaker sudo[14496]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:35 stefans-speaker sudo[14494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:13:35 stefans-speaker sudo[14494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:35 stefans-speaker sudo[14496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Sep 01 01:13:35 stefans-speaker sudo[14496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:35 stefans-speaker systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Sep 01 01:13:35 stefans-speaker systemd[1]: mpd_monitor.service: Deactivated successfully. Sep 01 01:13:35 stefans-speaker systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Sep 01 01:13:35 stefans-speaker systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Sep 01 01:13:35 stefans-speaker sudo[14496]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:35 stefans-speaker sudo[14494]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:35 stefans-speaker volumio[14201]: info: Successfully started MPD Monitor Sep 01 01:13:35 stefans-speaker volumio[14201]: info: Successfully started MPD Monitor Sep 01 01:13:35 stefans-speaker mpd_monitor.sh[14499]: MPD Monitor Service: Starting MPD Monitor Service Sep 01 01:13:35 stefans-speaker volumio[14201]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:35 stefans-speaker volumio[14201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:13:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 01 01:13:37 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:37 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:37 stefans-speaker go-librespot[14503]: go-librespot daemon starting... Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=debug msg="app state loaded" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:37 stefans-speaker volumio[14201]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+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 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+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 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+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 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=info msg="zeroconf server listening on port 41541" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=debug msg="obtained new client token: AAEPfrCx8BXoJzXMfjjpW+MmGlePo7Z3m558ESn5pOVff3c8AFS+68b8g3/X80PiCqVznEYdHeNkYFCTVdMJ27ovpDjcdueCp9GSVnf0O+VtAZ+pGwKLEPsZaxQL/e8mVhoKr5ZSPFm23/MNUaZJXWenpTzxPvVEvpv49xu5fPpcrwWr9ftKbIkjk5stcrx+3eAfV+rg1luc4TSg2b2qLSvzVwSjDGhGg3ESF/VkpGki40p7qx/eMdYc3Oc=" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=debug msg="completed challenge" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:37 stefans-speaker go-librespot[14504]: time="2026-09-01T01:13:37+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 01:13:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:37 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:38 stefans-speaker volumio[14201]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:38 stefans-speaker volumio[14201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin bluetooth to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin multiroom to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin metavolumio to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin cd_controller to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 01 01:13:40 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 01:13:40 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 01 01:13:40 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:40 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:40 stefans-speaker go-librespot[14511]: go-librespot daemon starting... Sep 01 01:13:40 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:40+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:40 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:40+02:00" level=debug msg="app state loaded" Sep 01 01:13:40 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+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 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+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 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+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 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+02:00" level=info msg="zeroconf server listening on port 35465" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+02:00" level=debug msg="obtained new client token: AAGVoBy9lVJdhSH0FSURSq8ISDVxcx/5sMcw5981ehmn4KqLvf6Qppar9hbSWSdeTgkPObYgBO3vS3fQ4cKfMuYGTNpT2TXrKd6VAFzWPO6oxcUxEiKcGaZvPOVQ1o9QkwGuMR6nT95JNgUi++ULo5OYhjG330lU0Za581Ub8YnUheBXSWw//l6zv6OrVioiAZkPkAwb76FyCy+4eGWRKSFG59BRGOykUpDcRv4hMsCg9BJxkt0ASv4A" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+02:00" level=debug msg="completed challenge" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:41 stefans-speaker go-librespot[14512]: time="2026-09-01T01:13:41+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 01:13:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:41 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:41 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 01:13:41 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 01:13:41 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:41 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:41 stefans-speaker volumio[14201]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 01:13:41 stefans-speaker volumio[14201]: info: MyVolumio login type: Token Sep 01 01:13:41 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 01:13:41 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 01 01:13:42 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 01 01:13:42 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 01 01:13:42 stefans-speaker volumio[14201]: info: Streaming services startup Sep 01 01:13:42 stefans-speaker volumio[14201]: info: Starting Streaming Daemon Sep 01 01:13:42 stefans-speaker sudo[14535]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:42 stefans-speaker volumio[14201]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 01 01:13:42 stefans-speaker sudo[14535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:13:42 stefans-speaker sudo[14535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:42 stefans-speaker sudo[14535]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:42 stefans-speaker volumio[14201]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:42 stefans-speaker volumio[14201]: error: Cannot start Volumio Streaming Daemon Sep 01 01:13:42 stefans-speaker volumio[14201]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:13:42 stefans-speaker volumio[14201]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:13:42 stefans-speaker volumio[14201]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:13:42 stefans-speaker volumio[14201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:13:43 stefans-speaker volumio[14201]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 01:13:43 stefans-speaker volumio[14201]: info: MyVolumio token set successfully Sep 01 01:13:43 stefans-speaker volumio[14201]: info: MYVOLUMIO: Adding device Sep 01 01:13:43 stefans-speaker volumio[14201]: info: MYVOLUMIO: Evaluating Server Sep 01 01:13:44 stefans-speaker volumio[14201]: info: MyVolumio status changed Sep 01 01:13:44 stefans-speaker volumio[14201]: info: Streaming services startup Sep 01 01:13:44 stefans-speaker volumio[14201]: info: Starting Streaming Daemon Sep 01 01:13:44 stefans-speaker volumio[14201]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 01:13:44 stefans-speaker volumio[14201]: info: Removing audio output: Sep 01 01:13:44 stefans-speaker volumio[14201]: info: Stoppping Tunnel 1 Sep 01 01:13:44 stefans-speaker sudo[14563]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:44 stefans-speaker sudo[14563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 01:13:44 stefans-speaker sudo[14563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:44 stefans-speaker sudo[14565]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:44 stefans-speaker sudo[14565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 01:13:44 stefans-speaker sudo[14565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:44 stefans-speaker sudo[14563]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:44 stefans-speaker volumio[14201]: error: Cannot start Volumio Streaming Daemon Sep 01 01:13:44 stefans-speaker volumio[14201]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 01:13:44 stefans-speaker volumio[14201]: sudo: unable to resolve host stefans-speaker: System error Sep 01 01:13:44 stefans-speaker volumio[14201]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 01:13:44 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 01:13:44 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 01:13:44 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 01:13:44 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 01:13:44 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 01:13:44 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 01:13:44 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 01:13:44 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 01:13:44 stefans-speaker sudo[14565]: pam_unix(sudo:session): session closed for user root Sep 01 01:13:44 stefans-speaker volumio[14201]: info: Remote SSH Stopped Sep 01 01:13:44 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 01 01:13:44 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:44 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:44 stefans-speaker go-librespot[14568]: go-librespot daemon starting... Sep 01 01:13:44 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:44+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:44 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:44+02:00" level=debug msg="app state loaded" Sep 01 01:13:44 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13: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 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13: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 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13: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 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:45+02:00" level=info msg="zeroconf server listening on port 44153" Sep 01 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:45+02:00" level=debug msg="obtained new client token: AAHIlak1NWgZzW8wjmdwmDo5bn7i/xBh6fSd2IQNPDBMzOtPhwA/I4DSgPjwaB5HI3EbViKeLZZVXxPEpADzJRU7r4twuXdthQMPYTA0h5KLr2+SrW19k2aBPKcrdMHTVwJPWhHGoeEhM8jC2lzgBoRrVbuu6Jt8haRSjATEm3KgWBYP9K/xaEDUk3c+MTHJVgsAPmVOriZxaslFKHDd3nAaSrVaaozKwzit8mTnp3CgEB1koxSCPhwO" Sep 01 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:45+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:45+02:00" level=debug msg="completed challenge" Sep 01 01:13:45 stefans-speaker go-librespot[14569]: time="2026-09-01T01:13:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Sep 01 01:13:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:45 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:45 stefans-speaker volumio[14201]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:45 stefans-speaker volumio[14201]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:13:46 stefans-speaker volumio[14201]: info: Setting Geolocation for MyVolumio to eu5 Sep 01 01:13:46 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:46 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:46 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:46 stefans-speaker volumio[14201]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:13:47 stefans-speaker volumio[14201]: info: Updating MyVolumio device info Sep 01 01:13:47 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:47 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:47 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:47 stefans-speaker volumio[14201]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 01:13:48 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 01 01:13:48 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:48 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:48 stefans-speaker go-librespot[14576]: go-librespot daemon starting... Sep 01 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+02:00" level=debug msg="app state loaded" Sep 01 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+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 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+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 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+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 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+02:00" level=info msg="zeroconf server listening on port 43917" Sep 01 01:13:48 stefans-speaker volumio[14201]: info: Initializing connection to go-librespot Websocket Sep 01 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+02:00" level=debug msg="new websocket client" Sep 01 01:13:48 stefans-speaker volumio[14201]: info: Connection to go-librespot Websocket established Sep 01 01:13:48 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:48+02:00" level=debug msg="obtained new client token: AAFgCwHU9xvf5C+5MWnSiJmw8qQblnuzHHe4lg4JR7/hYTYjq/mKjFMDBaYQSGUrhS0SebeCM0EfmUxd51f9NaB15kKBPRleDo1sAB7v5So9gJsG6ToYZoNbYE7jQyOszf07OrjZeWpwxKo1SDtz1DFfseeRj/b8ySxsuPKvD6HqzZ5YhLecT4cR8qbCua2Ms00PaheDt/T8Mcl5/t5VvwCq5Bly6pyyYbowwnPoNGl/L8y0ypykpH6CDL8=" Sep 01 01:13:49 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Sep 01 01:13:49 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:49+02:00" level=debug msg="completed keyexchange" Sep 01 01:13:49 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:49+02:00" level=debug msg="completed challenge" Sep 01 01:13:49 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:49+02:00" level=info msg="authenticated AP" username="31************************cy" Sep 01 01:13:49 stefans-speaker go-librespot[14577]: time="2026-09-01T01:13:49+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 01:13:49 stefans-speaker volumio[14201]: info: Connection to go-librespot Websocket closed Sep 01 01:13:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 01 01:13:49 stefans-speaker systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 01 01:13:50 stefans-speaker volumio[14201]: info: MYVOLUMIO: Adding device Sep 01 01:13:50 stefans-speaker volumio[14201]: info: MYVOLUMIO: Evaluating Server Sep 01 01:13:51 stefans-speaker volumio[14201]: info: Setting Geolocation for MyVolumio to eu12 Sep 01 01:13:51 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:51 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:51 stefans-speaker volumio[14201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 01:13:51 stefans-speaker volumio[14201]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 01:13:52 stefans-speaker volumio[14201]: info: Getting Spotify volume Sep 01 01:13:52 stefans-speaker volumio[14201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:13:52 stefans-speaker volumio[14201]: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 01 01:13:52 stefans-speaker volumio[14201]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Sep 01 01:13:52 stefans-speaker volumio[14201]: errno: -111, Sep 01 01:13:52 stefans-speaker volumio[14201]: code: 'ECONNREFUSED', Sep 01 01:13:52 stefans-speaker volumio[14201]: syscall: 'connect', Sep 01 01:13:52 stefans-speaker volumio[14201]: address: '127.0.0.1', Sep 01 01:13:52 stefans-speaker volumio[14201]: port: 9879, Sep 01 01:13:52 stefans-speaker volumio[14201]: response: undefined Sep 01 01:13:52 stefans-speaker volumio[14201]: } Sep 01 01:13:52 stefans-speaker volumio[14201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 01:13:52 stefans-speaker systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 01 01:13:52 stefans-speaker systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:52 stefans-speaker systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 01 01:13:52 stefans-speaker go-librespot[14628]: go-librespot daemon starting... Sep 01 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+02:00" level=info msg="running go-librespot 0.4.0" Sep 01 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+02:00" level=debug msg="app state loaded" Sep 01 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 01 01:13:52 stefans-speaker sudo[14638]: volumio : unable to resolve host stefans-speaker: System error Sep 01 01:13:52 stefans-speaker sudo[14638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-09-01 01:12' Sep 01 01:13:52 stefans-speaker sudo[14638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 01 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+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 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+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 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+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 01:13:52 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:52+02:00" level=info msg="zeroconf server listening on port 35097" Sep 01 01:13:53 stefans-speaker go-librespot[14629]: time="2026-09-01T01:13:53+02:00" level=debug msg="obtained new client token: AAHLgesXjiScYZjaLbaWqW4I3qvIEwq/IXDjQHethCHHZpKh5kToMfUZ8I86T4nfGfN8k3zxrondsFDnLUP9E2tLXBMB+0IA0Fks7Flr1VBiGGu9MI+D11/eqB08c0n6cBJzf6HYccV68fNo8G+iX+V5yuF4p9iKFNyfuEDDL5nWmG7Qfmif4+lYvWjM4UH95xw1msL1hEzd53j1ol+1Ejjw/lUTDdUjKDgRo2makwIEn+NrqOLAK6K9" 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"