Feb 24 11:01:00 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 16. Feb 24 11:01:00 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:00 volumio volumio[11739]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:01:00 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:00 volumio volumio[11739]: info: No valid Plugin REST Endpoint Feb 24 11:01:00 volumio qobuz-connect[12103]: 20260224 11:01:00.224 [12103.12103] ERROR SampleApp: api request was not successful Feb 24 11:01:00 volumio qobuz-connect[12103]: Error: Application ID was not specified! Feb 24 11:01:00 volumio qobuz-connect[12103]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:00 volumio qobuz-connect[12103]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:00 volumio qobuz-connect[12103]: -n|--deviceName: Device name Feb 24 11:01:00 volumio qobuz-connect[12103]: -l|--model: Device's model name Feb 24 11:01:00 volumio qobuz-connect[12103]: -s|--serialNumber: Device's serial number Feb 24 11:01:00 volumio qobuz-connect[12103]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:00 volumio qobuz-connect[12103]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:00 volumio qobuz-connect[12103]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:00 volumio qobuz-connect[12103]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:00 volumio qobuz-connect[12103]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:00 volumio qobuz-connect[12103]: -h|--help: Print this help Feb 24 11:01:00 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:00 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 24 11:01:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:01 volumio go-librespot[12104]: go-librespot daemon starting... Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="app state loaded" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=info msg="zeroconf server listening on port 42577" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="obtained new client token: AACx5Y/VRWtZDwslBL32oDxCrtRkNZjtW2qfpYFSV7tPQSYPjpYt06QppapBiq3mvMll7MIheqIok3e2uwLPcDt+8OTLOn2dbg5CgvnjvBjTQRo7pXJJKc9x2Km5ywLH/bHum4bYNNfhSZCml8xRDchcIl4lYVhQB3xdW3/978zGvtW9Uw9QUj0ALe8iJt2HexI6XUlmHxNUkX7udbgSKxcuOkMVOGcGZpJDPM7wdc4i+VQ/hIpTmWM=" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="completed challenge" Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:01 volumio go-librespot[12105]: time="2026-02-24T11:01:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=debug msg="completed challenge" Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=debug msg="completed challenge" Feb 24 11:01:02 volumio volumio[11739]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 24 11:01:02 volumio go-librespot[12105]: time="2026-02-24T11:01:02+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:03 volumio volumio[11739]: info: Initializing connection to go-librespot Websocket Feb 24 11:01:03 volumio go-librespot[12105]: time="2026-02-24T11:01:03+01:00" level=debug msg="new websocket client" Feb 24 11:01:03 volumio volumio[11739]: info: Connection to go-librespot Websocket established Feb 24 11:01:03 volumio go-librespot[12105]: time="2026-02-24T11:01:03+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:01:03 volumio go-librespot[12105]: time="2026-02-24T11:01:03+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:03 volumio go-librespot[12105]: time="2026-02-24T11:01:03+01:00" level=debug msg="completed challenge" Feb 24 11:01:03 volumio go-librespot[12105]: time="2026-02-24T11:01:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:04 volumio go-librespot[12105]: time="2026-02-24T11:01:04+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 11:01:04 volumio go-librespot[12105]: time="2026-02-24T11:01:04+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:04 volumio go-librespot[12105]: time="2026-02-24T11:01:04+01:00" level=debug msg="completed challenge" Feb 24 11:01:04 volumio go-librespot[12105]: time="2026-02-24T11:01:04+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:04 volumio go-librespot[12105]: time="2026-02-24T11:01:04+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:01:05 volumio go-librespot[12105]: time="2026-02-24T11:01:05+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:05 volumio go-librespot[12105]: time="2026-02-24T11:01:05+01:00" level=debug msg="completed challenge" Feb 24 11:01:05 volumio go-librespot[12105]: time="2026-02-24T11:01:05+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:05 volumio go-librespot[12105]: time="2026-02-24T11:01:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:05 volumio volumio[11739]: info: Connection to go-librespot Websocket closed Feb 24 11:01:05 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 17. Feb 24 11:01:05 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:05 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:05 volumio volumio[11739]: info: No valid Plugin REST Endpoint Feb 24 11:01:05 volumio qobuz-connect[12115]: 20260224 11:01:05.373 [12115.12115] ERROR SampleApp: api request was not successful Feb 24 11:01:05 volumio qobuz-connect[12115]: Error: Application ID was not specified! Feb 24 11:01:05 volumio qobuz-connect[12115]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:05 volumio qobuz-connect[12115]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:05 volumio qobuz-connect[12115]: -n|--deviceName: Device name Feb 24 11:01:05 volumio qobuz-connect[12115]: -l|--model: Device's model name Feb 24 11:01:05 volumio qobuz-connect[12115]: -s|--serialNumber: Device's serial number Feb 24 11:01:05 volumio qobuz-connect[12115]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:05 volumio qobuz-connect[12115]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:05 volumio qobuz-connect[12115]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:05 volumio qobuz-connect[12115]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:05 volumio qobuz-connect[12115]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:05 volumio qobuz-connect[12115]: -h|--help: Print this help Feb 24 11:01:05 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:05 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:06 volumio volumio[11739]: info: Getting Spotify volume Feb 24 11:01:06 volumio volumio[11739]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:01:06 volumio volumio[11739]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:01:06 volumio volumio[11739]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 24 11:01:06 volumio volumio[11739]: errno: -111, Feb 24 11:01:06 volumio volumio[11739]: code: 'ECONNREFUSED', Feb 24 11:01:06 volumio volumio[11739]: syscall: 'connect', Feb 24 11:01:06 volumio volumio[11739]: address: '127.0.0.1', Feb 24 11:01:06 volumio volumio[11739]: port: 9879, Feb 24 11:01:06 volumio volumio[11739]: response: undefined Feb 24 11:01:06 volumio volumio[11739]: } Feb 24 11:01:06 volumio volumio[11739]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:01:07 volumio sudo[12144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 11:00' Feb 24 11:01:07 volumio sudo[12144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:07 volumio sudo[12144]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:07 volumio volumio-remote-updater[715]: [2026-02-24 11:01:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 24 11:01:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 24 11:01:07 volumio systemd[1]: volumio.service: Consumed 18.696s CPU time. Feb 24 11:01:07 volumio volumio-remote-updater[715]: [2026-02-24 11:01:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 24 11:01:07 volumio volumio5-onboarding[11484]: time=2026-02-24T11:01:07.730+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 24 11:01:07 volumio volumio5-onboarding[11484]: time=2026-02-24T11:01:07.768+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 24 11:01:07 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 24 11:01:07 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 24 11:01:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 852. Feb 24 11:01:08 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 24 11:01:08 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 24 11:01:08 volumio systemd[1]: volumio.service: Consumed 18.696s CPU time. Feb 24 11:01:08 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 24 11:01:08 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 24 11:01:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 24 11:01:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:08 volumio go-librespot[12173]: go-librespot daemon starting... Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="app state loaded" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=info msg="zeroconf server listening on port 45881" Feb 24 11:01:08 volumio volumio5-onboarding[11484]: time=2026-02-24T11:01:08.770+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="obtained new client token: AAC4P4tUnnmy5QkoUH6dhpvV26pV/+d3uiai//yptdpAwu0aV5le9UZ8CElrcAKn5LEFPGnEEzIyptGWxk2POyQGsI4xEwaJa2D0hpSYLaJb0W7E3FEr8gnS1vZiYhtmIoCCj/lKLJ7kXuvsQGzeP0/1JLyayi+vl+15rSs3q1BwQBsD+YXKRETqpjw1lSHVZy9+DQmuTLfQLDxQVPuhj6m6n/E1Yp7hGmwuAFvePWHHMvIU6RcR+oo=" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=debug msg="completed challenge" Feb 24 11:01:08 volumio go-librespot[12174]: time="2026-02-24T11:01:08+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:09 volumio go-librespot[12174]: time="2026-02-24T11:01:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:09 volumio go-librespot[12174]: time="2026-02-24T11:01:09+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:09 volumio go-librespot[12174]: time="2026-02-24T11:01:09+01:00" level=debug msg="completed challenge" Feb 24 11:01:09 volumio go-librespot[12174]: time="2026-02-24T11:01:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:09 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:09 volumio volumio[12156]: info: ----- Volumio3 ---- Feb 24 11:01:09 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:09 volumio volumio[12156]: info: ----- System startup ---- Feb 24 11:01:09 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=debug msg="completed challenge" Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:10 volumio volumio[12156]: info: MYVOLUMIO Environment detected Feb 24 11:01:10 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 18. Feb 24 11:01:10 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:10 volumio volumio[12156]: info: Plugin folders cleanup Feb 24 11:01:10 volumio volumio[12156]: info: Scanning into folder /volumio/app/plugins/ Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category audio_interface Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category miscellanea Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category music_service Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category plugins.json Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category system_controller Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category user_interface Feb 24 11:01:10 volumio volumio[12156]: info: Scanning into folder /data/plugins/ Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category music_service Feb 24 11:01:10 volumio volumio[12156]: info: Scanning category user_interface Feb 24 11:01:10 volumio volumio[12156]: info: Plugin folders cleanup completed Feb 24 11:01:10 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:10 volumio volumio[12156]: info: ----- Core plugins startup ---- Feb 24 11:01:10 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:10 volumio volumio[12156]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 11:01:10 volumio volumio[12156]: info: Adding plugin upnp to MyMusic Plugins Feb 24 11:01:10 volumio volumio[12156]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 11:01:10 volumio volumio[12156]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 11:01:10 volumio volumio[12156]: info: Loading plugins from folder /data/plugins/ Feb 24 11:01:10 volumio volumio[12156]: info: Loading plugin "system"... Feb 24 11:01:10 volumio volumio[12156]: info: Loading plugin "appearance"... Feb 24 11:01:10 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:10 volumio go-librespot[12174]: time="2026-02-24T11:01:10+01:00" level=debug msg="completed challenge" Feb 24 11:01:11 volumio go-librespot[12174]: time="2026-02-24T11:01:11+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:11 volumio go-librespot[12174]: time="2026-02-24T11:01:11+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "network"... Feb 24 11:01:11 volumio volumio[12156]: info: Refreshing Cached IP Addresses Feb 24 11:01:11 volumio sudo[12197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:01:11 volumio sudo[12199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:01:11 volumio sudo[12199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:11 volumio sudo[12197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:11 volumio sudo[12199]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:11 volumio sudo[12197]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "services"... Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "volumio5onboarding"... Feb 24 11:01:11 volumio sudo[12208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 11:01:11 volumio sudo[12208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "alsa_controller"... Feb 24 11:01:11 volumio go-librespot[12174]: time="2026-02-24T11:01:11+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:11 volumio go-librespot[12174]: time="2026-02-24T11:01:11+01:00" level=debug msg="completed challenge" Feb 24 11:01:11 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "wizard"... Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "networkfs"... Feb 24 11:01:11 volumio volumio[12156]: info: Starting Udev Watcher for removable devices Feb 24 11:01:11 volumio volumio[12156]: info: Ignoring mount for partition: boot Feb 24 11:01:11 volumio volumio[12156]: info: Ignoring mount for partition: volumio Feb 24 11:01:11 volumio volumio[12156]: info: Ignoring mount for partition: volumio_data Feb 24 11:01:11 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "volumio_command_line_client"... Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "upnp"... Feb 24 11:01:11 volumio volumio[12156]: info: [1771927271880] Starting Upmpd Daemon Feb 24 11:01:11 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "my_music"... Feb 24 11:01:11 volumio volumio[12156]: info: Loading plugin "mpd"... Feb 24 11:01:11 volumio go-librespot[12174]: time="2026-02-24T11:01:11+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:12 volumio volumio[12156]: info: Loading plugin "upnp_browser"... Feb 24 11:01:12 volumio go-librespot[12174]: time="2026-02-24T11:01:12+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 24 11:01:12 volumio go-librespot[12174]: time="2026-02-24T11:01:12+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:12 volumio go-librespot[12174]: time="2026-02-24T11:01:12+01:00" level=debug msg="completed challenge" Feb 24 11:01:12 volumio go-librespot[12174]: time="2026-02-24T11:01:12+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:12 volumio go-librespot[12174]: time="2026-02-24T11:01:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:12 volumio volumio-remote-updater[715]: [2026-02-24 11:01:12] [connect] Successful connection Feb 24 11:01:13 volumio volumio[12156]: info: Starting UPNP Browser Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "alarm-clock"... Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "airplay_emulation"... Feb 24 11:01:13 volumio volumio[12156]: info: Starting Shairport Sync Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "last_100"... Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "webradio"... Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "i2s_dacs"... Feb 24 11:01:13 volumio volumio[12156]: info: I2S DAC not set, start Auto-detection Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "volumiodiscovery"... Feb 24 11:01:13 volumio volumio[12156]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:13 volumio node[12156]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:13 volumio volumio[12156]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:13 volumio volumio[12156]: *** WARNING *** For more information see Feb 24 11:01:13 volumio volumio[12156]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:13 volumio volumio[12156]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:13 volumio volumio[12156]: *** WARNING *** For more information see Feb 24 11:01:13 volumio node[12156]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:13 volumio node[12156]: *** WARNING *** For more information see Feb 24 11:01:13 volumio node[12156]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:13 volumio node[12156]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:13 volumio node[12156]: *** WARNING *** For more information see Feb 24 11:01:13 volumio volumio[12156]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 11:01:13 volumio volumio[12156]: info: Discovery: Started advertising with name: Volumio Feb 24 11:01:13 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "soundcloud"... Feb 24 11:01:13 volumio volumio[12156]: info: Loading plugin "spop"... Feb 24 11:01:14 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 24 11:01:14 volumio sudo[12208]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "outputs"... Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "albumart"... Feb 24 11:01:14 volumio volumio[12156]: info: Plugin example_plugin is not enabled Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "inputs"... Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "updater_comm"... Feb 24 11:01:14 volumio volumio[12156]: info: Plugin mpdemulation is not enabled Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "rest_api"... Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "websocket"... Feb 24 11:01:14 volumio volumio[12156]: info: Starting Socket.io Server version 1.7.4 Feb 24 11:01:14 volumio volumio[12156]: info: Loading plugin "touch_display"... Feb 24 11:01:15 volumio volumio[12246]: Forking 3 albumart workers Feb 24 11:01:15 volumio volumio[12156]: info: Applying required configuration parameters for plugin touch_display Feb 24 11:01:15 volumio volumio[12156]: info: Loading i18n strings for locale it Feb 24 11:01:15 volumio volumio[12156]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 11:01:15 volumio volumio[12156]: Updating browse sources language Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::initPlayerControls Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:01:15 volumio volumio[12156]: Express server listening on port 3000 Feb 24 11:01:15 volumio volumio[12156]: [Metrics] WebUI: 6s 311.29ms Feb 24 11:01:15 volumio volumio[12156]: info: CoreStateMachine::resetVolumioState Feb 24 11:01:15 volumio volumio[12156]: info: CoreStateMachine::getcurrentVolume Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 11:01:15 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 24 11:01:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:15 volumio volumio[12156]: info: Volumio Network Manager: Network status updated: 1 Feb 24 11:01:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:15 volumio go-librespot[12292]: go-librespot daemon starting... Feb 24 11:01:15 volumio go-librespot[12298]: time="2026-02-24T11:01:15+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:15 volumio volumio[12156]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 24 11:01:15 volumio go-librespot[12298]: time="2026-02-24T11:01:15+01:00" level=debug msg="app state loaded" Feb 24 11:01:15 volumio go-librespot[12298]: time="2026-02-24T11:01:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:16 volumio volumio[12156]: info: No valid Plugin REST Endpoint Feb 24 11:01:16 volumio qobuz-connect[12192]: 20260224 11:01:16.023 [12192.12192] ERROR SampleApp: api request was not successful Feb 24 11:01:16 volumio qobuz-connect[12192]: Error: Application ID was not specified! Feb 24 11:01:16 volumio qobuz-connect[12192]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:16 volumio qobuz-connect[12192]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:16 volumio qobuz-connect[12192]: -n|--deviceName: Device name Feb 24 11:01:16 volumio qobuz-connect[12192]: -l|--model: Device's model name Feb 24 11:01:16 volumio qobuz-connect[12192]: -s|--serialNumber: Device's serial number Feb 24 11:01:16 volumio qobuz-connect[12192]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:16 volumio qobuz-connect[12192]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:16 volumio qobuz-connect[12192]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:16 volumio qobuz-connect[12192]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:16 volumio qobuz-connect[12192]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:16 volumio qobuz-connect[12192]: -h|--help: Print this help Feb 24 11:01:16 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:16 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:16 volumio volumio[12156]: info: Reloading queue from file Feb 24 11:01:16 volumio volumio[12156]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=info msg="zeroconf server listening on port 39977" Feb 24 11:01:16 volumio volumio[12156]: info: VolumeController:: Volume=86 Mute =false Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::pushState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::updateTrackBlock Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrackBlock Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::setRepeat null single undefined Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::pushState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::setRandom false Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::pushState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:01:16 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 24 11:01:16 volumio systemd[1]: setdatetime-helper.service: Consumed 1.210s CPU time. Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:16 volumio volumio[12156]: info: Setting Device type: Raspberry PI Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="obtained new client token: AABMh18BQbG3VA9WGwlhWsfq96Hp5vvH4XTbePdIc0MGJUnfAkbTbHnJy998R6387343PaYLrOubXv3ReSTilI4mfuQZaR52WQaYO6ud5FohqXnTForeGEO7tmlXh9zKkQBbBfkUEOjeeZpMLCf6rXWO3YyFagHTi+rqFALbIcqTCJ2ACbfK3elAhpzF1F1FOz7v/W7v3ZSycR2CPspUzg025y6epI/5gS0UtDdicHWZyzyiTdrd" Feb 24 11:01:16 volumio volumio-remote-updater[715]: [2026-02-24 11:01:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771927272 101 Feb 24 11:01:16 volumio volumio[12156]: 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 Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:16 volumio volumio[12156]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="completed challenge" Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:16 volumio volumio[12156]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 24 11:01:16 volumio volumio[12156]: info: Discovery: adding 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 24 11:01:16 volumio volumio[12156]: info: Discovery: Found device Volumio Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio volumio[12156]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 24 11:01:16 volumio volumio[12256]: Starting albumart workers Feb 24 11:01:16 volumio volumio[12156]: info: VolumeController:: Volume=86 Mute =false Feb 24 11:01:16 volumio volumio[12156]: info: CoreStateMachine::pushState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:16 volumio volumio[12156]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 24 11:01:16 volumio volumio[12156]: info: Received Get System Info Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:01:16 volumio volumio[12156]: info: Discovery: Getting this device information Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 11:01:16 volumio volumio[12156]: info: Completed loading Core Plugins Feb 24 11:01:16 volumio volumio[12156]: info: Preparing to generate the ALSA configuration file Feb 24 11:01:16 volumio volumio[12156]: info: Discovery: this is already registered, 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 24 11:01:16 volumio volumio[12156]: info: Discovery: Found device Volumio Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:16 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:16 volumio volumio[12258]: Starting albumart workers Feb 24 11:01:16 volumio volumio[12257]: Starting albumart workers Feb 24 11:01:16 volumio volumio[12156]: info: Asound.conf file unchanged, so no further update is needed Feb 24 11:01:16 volumio volumio[12156]: info: Output device has changed, restarting MPD Feb 24 11:01:16 volumio volumio[12156]: info: Output device has changed, restarting Shairport Sync Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:16 volumio sudo[12328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 11:01:16 volumio sudo[12328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:16 volumio sudo[12326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 11:01:16 volumio sudo[12326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:16 volumio sudo[12326]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:16 volumio volumio[12156]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:01:16 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 24 11:01:16 volumio volumio[12156]: info: ___________ START PLUGINS ___________ Feb 24 11:01:16 volumio volumio[12156]: info: ControllerMpd::onStart: Initializing MPD Feb 24 11:01:16 volumio volumio[12156]: info: Creating MPD Configuration file Feb 24 11:01:16 volumio sudo[12338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:16 volumio sudo[12338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:16 volumio sudo[12336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 24 11:01:16 volumio volumio[12156]: info: [1771927276778] CoreMusicLibrary::Adding element Server multimediali Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:16 volumio sudo[12338]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:16 volumio sudo[12336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:16 volumio sudo[12340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 11:01:16 volumio sudo[12340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:16 volumio volumio[12156]: info: UPNP Browser: Client initialized successfully Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:16 volumio sudo[12336]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:16 volumio volumio[12156]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=debug msg="completed challenge" Feb 24 11:01:16 volumio volumio[12156]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:16 volumio volumio[12156]: info: [1771927276938] CoreMusicLibrary::Adding element Last_100 Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:16 volumio volumio[12156]: info: [1771927276948] CoreMusicLibrary::Adding element Webradio Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:16 volumio go-librespot[12298]: time="2026-02-24T11:01:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:16 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:16 volumio volumio[12156]: info: Initializing BBC Radios Feb 24 11:01:17 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:17 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:17 volumio volumio[12156]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:17 volumio volumio[12156]: info: [1771927277112] CoreMusicLibrary::Adding element SoundCloud Feb 24 11:01:17 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:17 volumio volumio[12156]: Cannot find translation for source SoundCloud Feb 24 11:01:17 volumio volumio[12156]: info: Creating Spotify config file Feb 24 11:01:17 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:17 volumio volumio[12156]: info: Loading i18n strings for locale it Feb 24 11:01:17 volumio volumio[12156]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 11:01:17 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:17 volumio volumio[12156]: info: Volumio Calling Home Feb 24 11:01:17 volumio sudo[12385]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 24 11:01:17 volumio sudo[12385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:17 volumio sudo[12385]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:17 volumio go-librespot[12298]: time="2026-02-24T11:01:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:17 volumio sudo[12390]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 24 11:01:17 volumio sudo[12390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:17 volumio go-librespot[12298]: time="2026-02-24T11:01:17+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:17 volumio sudo[12392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 24 11:01:17 volumio sudo[12392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:17 volumio go-librespot[12298]: time="2026-02-24T11:01:17+01:00" level=debug msg="completed challenge" Feb 24 11:01:17 volumio sudo[12394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 24 11:01:17 volumio sudo[12394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:17 volumio go-librespot[12298]: time="2026-02-24T11:01:17+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:17 volumio systemd[1]: Reloading. Feb 24 11:01:17 volumio volumio[12156]: info: touch_display: No backlight interface detected. Feb 24 11:01:17 volumio volumio[12156]: info: MPD Permissions set Feb 24 11:01:17 volumio volumio[12156]: info: MPD Permissions set Feb 24 11:01:17 volumio volumio[12156]: info: Volumio called home Feb 24 11:01:17 volumio volumio[12156]: info: Spotify config file written Feb 24 11:01:17 volumio sudo[12401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 24 11:01:17 volumio sudo[12401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:17 volumio sudo[12410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 24 11:01:17 volumio sudo[12410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:18 volumio sudo[12401]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:18 volumio volumio[12156]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 24 11:01:18 volumio volumio[12156]: info: No need to fix Spotify hosts Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:01:18 volumio volumio[12156]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 24 11:01:18 volumio volumio[12156]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 24 11:01:18 volumio volumio[12156]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 24 11:01:18 volumio sudo[12439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 24 11:01:18 volumio volumio[12156]: info: Starting Shairport Sync Feb 24 11:01:18 volumio sudo[12439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:18 volumio sudo[12441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 24 11:01:18 volumio sudo[12441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:18 volumio volumio[12156]: info: Starting Shairport Sync Feb 24 11:01:18 volumio sudo[12439]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:18 volumio sudo[12441]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:18 volumio volumio[12156]: info: Starting Shairport Sync Feb 24 11:01:18 volumio sudo[12445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=debug msg="completed challenge" Feb 24 11:01:18 volumio sudo[12445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:18 volumio sudo[12447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:01:18 volumio sudo[12447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:18 volumio sudo[12450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:01:18 volumio sudo[12450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:18 volumio volumio[12156]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 24 11:01:18 volumio volumio[12156]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 24 11:01:18 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:18 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:18 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:18 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:18 volumio go-librespot[12298]: time="2026-02-24T11:01:18+01:00" level=debug msg="completed challenge" Feb 24 11:01:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:18 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:19 volumio go-librespot[12298]: time="2026-02-24T11:01:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:19 volumio sudo[12394]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:19 volumio volumio[12156]: info: touch_display: systemctl daemon-reload succeeded. Feb 24 11:01:19 volumio sudo[12456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 24 11:01:19 volumio sudo[12456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:19 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 24 11:01:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 11:01:19 volumio systemd[1]: mpd.service: Consumed 3.894s CPU time. Feb 24 11:01:19 volumio systemd[1]: Reloading. Feb 24 11:01:19 volumio go-librespot[12298]: time="2026-02-24T11:01:19+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:01:19 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:19 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:19 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:19 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:19 volumio go-librespot[12298]: time="2026-02-24T11:01:19+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:19 volumio go-librespot[12298]: time="2026-02-24T11:01:19+01:00" level=debug msg="completed challenge" Feb 24 11:01:20 volumio sudo[12392]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 24 11:01:20 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 24 11:01:20 volumio volumio[12156]: error: Upnp client error: Error: read ECONNRESET Feb 24 11:01:20 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 11:01:20 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 11:01:20 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 11:01:20 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 24 11:01:20 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 11:01:20 volumio sudo[12456]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: Volumio Kiosk started. Feb 24 11:01:20 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 11:01:20 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 11:01:20 volumio systemd[1]: shairport-sync.service: Consumed 1.837s CPU time. Feb 24 11:01:20 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 24 11:01:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:20 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:20 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:20 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:20 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:20 volumio sudo[12390]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 24 11:01:20 volumio volumio[12156]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 24 11:01:20 volumio sudo[12476]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 11:01:20 volumio sudo[12476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 11:01:20 volumio sudo[12476]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:20 volumio go-librespot[12479]: go-librespot daemon starting... Feb 24 11:01:20 volumio sudo[12410]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 11:01:20 volumio sudo[12445]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio sudo[12450]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio sudo[12447]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:20 volumio volumio[12156]: info: Shairport-Sync Started Feb 24 11:01:20 volumio volumio[12156]: Error adding Membership: Error: addMembership EINVAL Feb 24 11:01:20 volumio volumio[12156]: info: Shairport-Sync Started Feb 24 11:01:20 volumio volumio[12156]: info: Shairport-Sync Started Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="app state loaded" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: X display number found: 0 Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: X display number found: 0 Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: X display number found: 0 Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=info msg="zeroconf server listening on port 36551" Feb 24 11:01:20 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:20 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:20 volumio volumio[12156]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:20 volumio volumio[12156]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:20 volumio volumio[12156]: info: touch_display: Setting screensaver timeout to 200 seconds. Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="obtained new client token: AADP4obyPPtOqCGLHATk7fgazFKQGk4s/aBD0dHmjO5FMdskOTMkNS0z6Jnj3T7PofJv6i6gq2mpEMygTRgObLgbaWwvh2b3FLBXDWW1XCVCo9KIhX2uzgPxUv6IcTg9xZQsphjStp0QlfdIMOaQGPD0g6NLdvD/vK2g408cCW8W8N8QmaNow+hBonoP5gPcothVmg9SE11Ml94wsoF19x+G/LzG8aWqqEa/xDxRK3OVKiUGK8WfTfM=" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:20 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 11:01:20 volumio volumio[12156]: info: Listing playlists Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=debug msg="completed challenge" Feb 24 11:01:20 volumio go-librespot[12481]: time="2026-02-24T11:01:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:21 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 19. Feb 24 11:01:21 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:21 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=debug msg="completed challenge" Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:21 volumio volumio[12156]: info: No valid Plugin REST Endpoint Feb 24 11:01:21 volumio qobuz-connect[12517]: 20260224 11:01:21.425 [12517.12517] ERROR SampleApp: api request was not successful Feb 24 11:01:21 volumio qobuz-connect[12517]: Error: Application ID was not specified! Feb 24 11:01:21 volumio qobuz-connect[12517]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:21 volumio qobuz-connect[12517]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:21 volumio qobuz-connect[12517]: -n|--deviceName: Device name Feb 24 11:01:21 volumio qobuz-connect[12517]: -l|--model: Device's model name Feb 24 11:01:21 volumio qobuz-connect[12517]: -s|--serialNumber: Device's serial number Feb 24 11:01:21 volumio qobuz-connect[12517]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:21 volumio qobuz-connect[12517]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:21 volumio qobuz-connect[12517]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:21 volumio qobuz-connect[12517]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:21 volumio qobuz-connect[12517]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:21 volumio qobuz-connect[12517]: -h|--help: Print this help Feb 24 11:01:21 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:21 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:21 volumio sudo[12535]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:01:21 volumio sudo[12535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:21 volumio sudo[12535]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:21 volumio sudo[12534]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:01:21 volumio sudo[12534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=debug msg="completed challenge" Feb 24 11:01:21 volumio sudo[12534]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:21 volumio sudo[12539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 11:01:21 volumio sudo[12539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:21 volumio go-librespot[12481]: time="2026-02-24T11:01:21+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:21 volumio sudo[12539]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:22 volumio volumio[12156]: info: Upmpdcli Daemon Started Feb 24 11:01:22 volumio go-librespot[12481]: time="2026-02-24T11:01:22+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:01:22 volumio go-librespot[12481]: time="2026-02-24T11:01:22+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:22 volumio go-librespot[12481]: time="2026-02-24T11:01:22+01:00" level=debug msg="completed challenge" Feb 24 11:01:22 volumio go-librespot[12481]: time="2026-02-24T11:01:22+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:23 volumio go-librespot[12481]: time="2026-02-24T11:01:23+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 11:01:23 volumio volumio[12156]: info: go-librespot daemon successfully initialized Feb 24 11:01:23 volumio go-librespot[12481]: time="2026-02-24T11:01:23+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 24 11:01:23 volumio mpd[12491]: 2026-02-24T11:01:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 11:01:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 24 11:01:24 volumio sudo[12328]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:24 volumio sudo[12340]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:24 volumio volumio[12156]: info: Completed starting Core Plugins Feb 24 11:01:24 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:24 volumio volumio[12156]: info: ----- MyVolumio plugins startup ---- Feb 24 11:01:24 volumio volumio[12156]: info: ------------------------------------------- Feb 24 11:01:24 volumio volumio[12156]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 11:01:24 volumio go-librespot[12481]: time="2026-02-24T11:01:24+01:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Feb 24 11:01:24 volumio go-librespot[12481]: time="2026-02-24T11:01:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:24 volumio go-librespot[12481]: time="2026-02-24T11:01:24+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:24 volumio go-librespot[12481]: time="2026-02-24T11:01:24+01:00" level=debug msg="completed challenge" Feb 24 11:01:24 volumio go-librespot[12481]: time="2026-02-24T11:01:24+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:24 volumio go-librespot[12481]: time="2026-02-24T11:01:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:24 volumio volumio[12156]: error: MPD error: The expression evaluated to a falsy value: Feb 24 11:01:24 volumio volumio[12156]: assert.ok(self.idling) Feb 24 11:01:24 volumio volumio[12156]: error: The expression evaluated to a falsy value: Feb 24 11:01:24 volumio volumio[12156]: assert.ok(self.idling) Feb 24 11:01:24 volumio volumio[12156]: error: updateQueue error: null Feb 24 11:01:24 volumio volumio[12156]: info: MPD running with PID12491 Feb 24 11:01:24 volumio volumio[12156]: ,establishing connection Feb 24 11:01:24 volumio volumio[12156]: error: updateQueue error: null Feb 24 11:01:26 volumio volumio[12156]: info: Initializing connection to go-librespot Websocket Feb 24 11:01:26 volumio volumio[12156]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:01:26 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 20. Feb 24 11:01:26 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:26 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:26 volumio volumio[12156]: info: No valid Plugin REST Endpoint Feb 24 11:01:26 volumio qobuz-connect[12557]: 20260224 11:01:26.768 [12557.12557] ERROR SampleApp: api request was not successful Feb 24 11:01:26 volumio qobuz-connect[12557]: Error: Application ID was not specified! Feb 24 11:01:26 volumio qobuz-connect[12557]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:26 volumio qobuz-connect[12557]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:26 volumio qobuz-connect[12557]: -n|--deviceName: Device name Feb 24 11:01:26 volumio qobuz-connect[12557]: -l|--model: Device's model name Feb 24 11:01:26 volumio qobuz-connect[12557]: -s|--serialNumber: Device's serial number Feb 24 11:01:26 volumio qobuz-connect[12557]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:26 volumio qobuz-connect[12557]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:26 volumio qobuz-connect[12557]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:26 volumio qobuz-connect[12557]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:26 volumio qobuz-connect[12557]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:26 volumio qobuz-connect[12557]: -h|--help: Print this help Feb 24 11:01:26 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:26 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 24 11:01:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:27 volumio go-librespot[12558]: go-librespot daemon starting... Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="app state loaded" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=info msg="zeroconf server listening on port 37861" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="obtained new client token: AACTnjZJMGAThnZNk37mNxqRCO+ketRQjoUhpWpRYqW1NS/diM4+kSxXR7SrRJYyjs1LMz4dBb9QoRTpzIU8QBB7Wf0Z7JsTgCOUAITEeDdIFb9FDEZebDa3C61ujBOFF5ZqWZDVbOcNdVkmLYmf/vrBwxzk0f8rmwLp0RUFkMjOX9whVMAO4CVu0z75XOOyDPtRzeJTxyjsXVjMZguaOaT6BS0c+1hbHEyW44Vpxf/iqaWqDsUqh4A=" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=debug msg="completed challenge" Feb 24 11:01:27 volumio go-librespot[12559]: time="2026-02-24T11:01:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=debug msg="completed challenge" Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=debug msg="completed challenge" Feb 24 11:01:28 volumio go-librespot[12559]: time="2026-02-24T11:01:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:29 volumio volumio[12156]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 24 11:01:29 volumio volumio[12156]: info: Initializing connection to go-librespot Websocket Feb 24 11:01:29 volumio go-librespot[12559]: time="2026-02-24T11:01:29+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:01:29 volumio go-librespot[12559]: time="2026-02-24T11:01:29+01:00" level=debug msg="new websocket client" Feb 24 11:01:29 volumio volumio[12156]: info: Connection to go-librespot Websocket established Feb 24 11:01:29 volumio go-librespot[12559]: time="2026-02-24T11:01:29+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:29 volumio go-librespot[12559]: time="2026-02-24T11:01:29+01:00" level=debug msg="completed challenge" Feb 24 11:01:29 volumio go-librespot[12559]: time="2026-02-24T11:01:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:30 volumio go-librespot[12559]: time="2026-02-24T11:01:30+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Feb 24 11:01:30 volumio go-librespot[12559]: time="2026-02-24T11:01:30+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:01:30 volumio go-librespot[12559]: time="2026-02-24T11:01:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:42698->104.199.241.202:80: read: connection reset by peer" Feb 24 11:01:31 volumio go-librespot[12559]: time="2026-02-24T11:01:31+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 24 11:01:31 volumio go-librespot[12559]: time="2026-02-24T11:01:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:31 volumio go-librespot[12559]: time="2026-02-24T11:01:31+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:31 volumio go-librespot[12559]: time="2026-02-24T11:01:31+01:00" level=debug msg="completed challenge" Feb 24 11:01:31 volumio go-librespot[12559]: time="2026-02-24T11:01:31+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:31 volumio go-librespot[12559]: time="2026-02-24T11:01:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:31 volumio volumio[12156]: info: Connection to go-librespot Websocket closed Feb 24 11:01:32 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 21. Feb 24 11:01:32 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:32 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:32 volumio volumio[12156]: info: No valid Plugin REST Endpoint Feb 24 11:01:32 volumio qobuz-connect[12582]: 20260224 11:01:32.076 [12582.12582] ERROR SampleApp: api request was not successful Feb 24 11:01:32 volumio qobuz-connect[12582]: Error: Application ID was not specified! Feb 24 11:01:32 volumio qobuz-connect[12582]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:32 volumio qobuz-connect[12582]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:32 volumio qobuz-connect[12582]: -n|--deviceName: Device name Feb 24 11:01:32 volumio qobuz-connect[12582]: -l|--model: Device's model name Feb 24 11:01:32 volumio qobuz-connect[12582]: -s|--serialNumber: Device's serial number Feb 24 11:01:32 volumio qobuz-connect[12582]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:32 volumio qobuz-connect[12582]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:32 volumio qobuz-connect[12582]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:32 volumio qobuz-connect[12582]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:32 volumio qobuz-connect[12582]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:32 volumio qobuz-connect[12582]: -h|--help: Print this help Feb 24 11:01:32 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:32 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:32 volumio volumio[12156]: info: Getting Spotify volume Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin bluetooth to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin multiroom to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin metavolumio to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin cd_controller to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 24 11:01:32 volumio volumio[12156]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 24 11:01:34 volumio volumio[12156]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 24 11:01:34 volumio volumio[12156]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 24 11:01:34 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:34 volumio volumio[12156]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:34 volumio volumio[12156]: info: Starting MyVolumio Remote Streaming Endpoints Feb 24 11:01:34 volumio volumio[12156]: info: MyVolumio login type: Token Feb 24 11:01:34 volumio volumio[12156]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 24 11:01:34 volumio volumio[12156]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 24 11:01:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 24 11:01:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:34 volumio go-librespot[12584]: go-librespot daemon starting... Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="app state loaded" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=info msg="zeroconf server listening on port 43593" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="obtained new client token: AAAi0pxnXLGIsIlwUkpxjJ99JYNaKeVJTh+eT89ugo64xSQvsAormoltmMHFERNADAN36zM4LvI67tKgJYcsNcG7rq4ojUL9rjSlvcWwOMu7/TKpWnyzWxu5ZXm8niBskOomkqCUAlqydywYgQWDaSWO29rkj12cqlg6dCNnvLafm6sWezLBhhKyJUwMPhkjgt9Ullg5ESiEE/fARGMSroRHoeHiTZXEF9kxnr1oFA7wP5NyTOpw3Y4=" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=debug msg="completed challenge" Feb 24 11:01:34 volumio go-librespot[12585]: time="2026-02-24T11:01:34+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:35 volumio volumio[12156]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 24 11:01:35 volumio volumio[12156]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 24 11:01:35 volumio volumio[12156]: info: Streaming services startup Feb 24 11:01:35 volumio volumio[12156]: info: Starting Streaming Daemon Feb 24 11:01:35 volumio volumio[12156]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 24 11:01:35 volumio volumio[12156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:01:35 volumio volumio[12156]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:01:35 volumio volumio[12156]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 24 11:01:35 volumio volumio[12156]: errno: -111, Feb 24 11:01:35 volumio volumio[12156]: code: 'ECONNREFUSED', Feb 24 11:01:35 volumio volumio[12156]: syscall: 'connect', Feb 24 11:01:35 volumio volumio[12156]: address: '127.0.0.1', Feb 24 11:01:35 volumio volumio[12156]: port: 9879, Feb 24 11:01:35 volumio volumio[12156]: response: undefined Feb 24 11:01:35 volumio volumio[12156]: } Feb 24 11:01:35 volumio volumio[12156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:01:35 volumio sudo[12596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 11:01:35 volumio sudo[12596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:35 volumio sudo[12596]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:35 volumio go-librespot[12585]: time="2026-02-24T11:01:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:35 volumio go-librespot[12585]: time="2026-02-24T11:01:35+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:35 volumio go-librespot[12585]: time="2026-02-24T11:01:35+01:00" level=debug msg="completed challenge" Feb 24 11:01:35 volumio go-librespot[12585]: time="2026-02-24T11:01:35+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=debug msg="completed challenge" Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:36 volumio sudo[12616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 11:00' Feb 24 11:01:36 volumio sudo[12616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:36 volumio sudo[12616]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 24 11:01:36 volumio systemd[1]: volumio.service: Consumed 21.572s CPU time. Feb 24 11:01:36 volumio volumio-remote-updater[715]: [2026-02-24 11:01:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 24 11:01:36 volumio volumio-remote-updater[715]: [2026-02-24 11:01:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 24 11:01:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 24 11:01:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 24 11:01:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 853. Feb 24 11:01:36 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 24 11:01:36 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 24 11:01:36 volumio systemd[1]: volumio.service: Consumed 21.572s CPU time. Feb 24 11:01:36 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:01:36 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 24 11:01:36 volumio volumio5-onboarding[11484]: time=2026-02-24T11:01:36.712+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 24 11:01:36 volumio volumio5-onboarding[11484]: time=2026-02-24T11:01:36.886+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:36 volumio go-librespot[12585]: time="2026-02-24T11:01:36+01:00" level=debug msg="completed challenge" Feb 24 11:01:37 volumio go-librespot[12585]: time="2026-02-24T11:01:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:37 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 22. Feb 24 11:01:37 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:37 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:37 volumio qobuz-connect[12643]: Error: Application ID was not specified! Feb 24 11:01:37 volumio qobuz-connect[12643]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:37 volumio qobuz-connect[12643]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:37 volumio qobuz-connect[12643]: -n|--deviceName: Device name Feb 24 11:01:37 volumio qobuz-connect[12643]: -l|--model: Device's model name Feb 24 11:01:37 volumio qobuz-connect[12643]: -s|--serialNumber: Device's serial number Feb 24 11:01:37 volumio qobuz-connect[12643]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:37 volumio qobuz-connect[12643]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:37 volumio qobuz-connect[12643]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:37 volumio qobuz-connect[12643]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:37 volumio qobuz-connect[12643]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:37 volumio qobuz-connect[12643]: -h|--help: Print this help Feb 24 11:01:37 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:37 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:37 volumio go-librespot[12585]: time="2026-02-24T11:01:37+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 11:01:37 volumio go-librespot[12585]: time="2026-02-24T11:01:37+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:37 volumio go-librespot[12585]: time="2026-02-24T11:01:37+01:00" level=debug msg="completed challenge" Feb 24 11:01:37 volumio go-librespot[12585]: time="2026-02-24T11:01:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:37 volumio volumio5-onboarding[11484]: time=2026-02-24T11:01:37.890+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 24 11:01:38 volumio go-librespot[12585]: time="2026-02-24T11:01:38+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:01:38 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:38 volumio volumio[12628]: info: ----- Volumio3 ---- Feb 24 11:01:38 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:38 volumio volumio[12628]: info: ----- System startup ---- Feb 24 11:01:38 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:38 volumio go-librespot[12585]: time="2026-02-24T11:01:38+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:38 volumio go-librespot[12585]: time="2026-02-24T11:01:38+01:00" level=debug msg="completed challenge" Feb 24 11:01:38 volumio go-librespot[12585]: time="2026-02-24T11:01:38+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:38 volumio go-librespot[12585]: time="2026-02-24T11:01:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:39 volumio volumio[12628]: info: MYVOLUMIO Environment detected Feb 24 11:01:39 volumio volumio[12628]: info: Plugin folders cleanup Feb 24 11:01:39 volumio volumio[12628]: info: Scanning into folder /volumio/app/plugins/ Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category audio_interface Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category miscellanea Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category music_service Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category plugins.json Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category system_controller Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category user_interface Feb 24 11:01:39 volumio volumio[12628]: info: Scanning into folder /data/plugins/ Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category music_service Feb 24 11:01:39 volumio volumio[12628]: info: Scanning category user_interface Feb 24 11:01:39 volumio volumio[12628]: info: Plugin folders cleanup completed Feb 24 11:01:39 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:39 volumio volumio[12628]: info: ----- Core plugins startup ---- Feb 24 11:01:39 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:39 volumio volumio[12628]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 11:01:39 volumio volumio[12628]: info: Adding plugin upnp to MyMusic Plugins Feb 24 11:01:39 volumio volumio[12628]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 11:01:39 volumio volumio[12628]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 11:01:39 volumio volumio[12628]: info: Loading plugins from folder /data/plugins/ Feb 24 11:01:39 volumio volumio[12628]: info: Loading plugin "system"... Feb 24 11:01:39 volumio volumio[12628]: info: Loading plugin "appearance"... Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "network"... Feb 24 11:01:40 volumio volumio[12628]: info: Refreshing Cached IP Addresses Feb 24 11:01:40 volumio sudo[12659]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:01:40 volumio sudo[12659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "services"... Feb 24 11:01:40 volumio sudo[12661]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:01:40 volumio sudo[12661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:40 volumio sudo[12659]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:40 volumio sudo[12661]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "volumio5onboarding"... Feb 24 11:01:40 volumio sudo[12674]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 11:01:40 volumio sudo[12674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "alsa_controller"... Feb 24 11:01:40 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "wizard"... Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "networkfs"... Feb 24 11:01:40 volumio volumio[12628]: info: Starting Udev Watcher for removable devices Feb 24 11:01:40 volumio volumio[12628]: info: Ignoring mount for partition: boot Feb 24 11:01:40 volumio volumio[12628]: info: Ignoring mount for partition: volumio Feb 24 11:01:40 volumio volumio[12628]: info: Ignoring mount for partition: volumio_data Feb 24 11:01:40 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "volumio_command_line_client"... Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "upnp"... Feb 24 11:01:40 volumio volumio[12628]: info: [1771927300434] Starting Upmpd Daemon Feb 24 11:01:40 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "my_music"... Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "mpd"... Feb 24 11:01:40 volumio volumio[12628]: info: Loading plugin "upnp_browser"... Feb 24 11:01:41 volumio volumio[12628]: info: Starting UPNP Browser Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "alarm-clock"... Feb 24 11:01:41 volumio volumio-remote-updater[715]: [2026-02-24 11:01:41] [connect] Successful connection Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "airplay_emulation"... Feb 24 11:01:41 volumio volumio[12628]: info: Starting Shairport Sync Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "last_100"... Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "webradio"... Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "i2s_dacs"... Feb 24 11:01:41 volumio volumio[12628]: info: I2S DAC not set, start Auto-detection Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "volumiodiscovery"... Feb 24 11:01:41 volumio volumio[12628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:41 volumio volumio[12628]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:41 volumio volumio[12628]: *** WARNING *** For more information see Feb 24 11:01:41 volumio volumio[12628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:41 volumio volumio[12628]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:41 volumio volumio[12628]: *** WARNING *** For more information see Feb 24 11:01:41 volumio node[12628]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:41 volumio node[12628]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:41 volumio node[12628]: *** WARNING *** For more information see Feb 24 11:01:41 volumio node[12628]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 11:01:41 volumio node[12628]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:01:41 volumio node[12628]: *** WARNING *** For more information see Feb 24 11:01:41 volumio volumio[12628]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 11:01:41 volumio volumio[12628]: info: Discovery: Started advertising with name: Volumio Feb 24 11:01:41 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:01:41 volumio volumio[12628]: info: Loading plugin "soundcloud"... Feb 24 11:01:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 24 11:01:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:42 volumio go-librespot[12694]: go-librespot daemon starting... Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="app state loaded" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=info msg="zeroconf server listening on port 33643" Feb 24 11:01:42 volumio volumio[12628]: info: Loading plugin "spop"... Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="obtained new client token: AABj4FVRP3jJrRLYaCmBqF1v7yaU7TA3rj92YIMtFu0W09P3bEltKAPVflsT6ma2OPGiHyRANWOem/8BNqbURcXvAwT+p9+vhgu2y47ih9VszXzEsnYd8Z5fVebfGhndANVUg9J6+L+YwjjvgWdY+UiG5r4cYUjsDCV3XF06TdtEO02qihJ24iud9Xvo6xLh94CwsNDJy4BXyUYsiH4yHFFfGZikQYJi3mVTnELZ7V53eUh2zFoS/ro=" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=debug msg="completed challenge" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:42 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 23. Feb 24 11:01:42 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:42 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 24 11:01:42 volumio go-librespot[12695]: time="2026-02-24T11:01:42+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Feb 24 11:01:43 volumio sudo[12674]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:43 volumio go-librespot[12695]: time="2026-02-24T11:01:43+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 24 11:01:43 volumio go-librespot[12695]: time="2026-02-24T11:01:43+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:43 volumio go-librespot[12695]: time="2026-02-24T11:01:43+01:00" level=debug msg="completed challenge" Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "outputs"... Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "albumart"... Feb 24 11:01:43 volumio volumio[12628]: info: Plugin example_plugin is not enabled Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "inputs"... Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "updater_comm"... Feb 24 11:01:43 volumio go-librespot[12695]: time="2026-02-24T11:01:43+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:43 volumio volumio[12628]: info: Plugin mpdemulation is not enabled Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "rest_api"... Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "websocket"... Feb 24 11:01:43 volumio volumio[12628]: info: Starting Socket.io Server version 1.7.4 Feb 24 11:01:43 volumio volumio[12628]: info: Loading plugin "touch_display"... Feb 24 11:01:43 volumio volumio[12628]: info: Applying required configuration parameters for plugin touch_display Feb 24 11:01:43 volumio volumio[12706]: Forking 3 albumart workers Feb 24 11:01:43 volumio volumio[12628]: info: Loading i18n strings for locale it Feb 24 11:01:43 volumio volumio[12628]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 11:01:43 volumio volumio[12628]: Updating browse sources language Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::initPlayerControls Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:01:43 volumio volumio[12628]: Express server listening on port 3000 Feb 24 11:01:43 volumio volumio[12628]: [Metrics] WebUI: 5s 988.20ms Feb 24 11:01:43 volumio volumio[12628]: info: CoreStateMachine::resetVolumioState Feb 24 11:01:43 volumio volumio[12628]: info: CoreStateMachine::getcurrentVolume Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 11:01:43 volumio go-librespot[12695]: time="2026-02-24T11:01:43+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 24 11:01:43 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:44 volumio volumio[12628]: info: Volumio Network Manager: Network status updated: 1 Feb 24 11:01:44 volumio go-librespot[12695]: time="2026-02-24T11:01:44+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:44 volumio go-librespot[12695]: time="2026-02-24T11:01:44+01:00" level=debug msg="completed challenge" Feb 24 11:01:44 volumio volumio[12628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 24 11:01:44 volumio volumio[12628]: info: VolumeController:: Volume=86 Mute =false Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::pushState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:44 volumio go-librespot[12695]: time="2026-02-24T11:01:44+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::updateTrackBlock Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrackBlock Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 11:01:44 volumio volumio[12628]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 24 11:01:44 volumio volumio[12628]: info: Reloading queue from file Feb 24 11:01:44 volumio volumio-remote-updater[715]: [2026-02-24 11:01:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771927301 101 Feb 24 11:01:44 volumio volumio[12628]: 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 Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::setRepeat null single undefined Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::pushState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::setRandom false Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::pushState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:44 volumio volumio[12628]: info: Setting Device type: Raspberry PI Feb 24 11:01:44 volumio volumio[12628]: info: No valid Plugin REST Endpoint Feb 24 11:01:44 volumio qobuz-connect[12705]: 20260224 11:01:44.475 [12705.12705] ERROR SampleApp: api request was not successful Feb 24 11:01:44 volumio qobuz-connect[12705]: Error: Application ID was not specified! Feb 24 11:01:44 volumio qobuz-connect[12705]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:44 volumio qobuz-connect[12705]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:44 volumio qobuz-connect[12705]: -n|--deviceName: Device name Feb 24 11:01:44 volumio qobuz-connect[12705]: -l|--model: Device's model name Feb 24 11:01:44 volumio qobuz-connect[12705]: -s|--serialNumber: Device's serial number Feb 24 11:01:44 volumio qobuz-connect[12705]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:44 volumio qobuz-connect[12705]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:44 volumio qobuz-connect[12705]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:44 volumio qobuz-connect[12705]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:44 volumio qobuz-connect[12705]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:44 volumio qobuz-connect[12705]: -h|--help: Print this help Feb 24 11:01:44 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:44 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:44 volumio volumio[12716]: Starting albumart workers Feb 24 11:01:44 volumio volumio[12717]: Starting albumart workers Feb 24 11:01:44 volumio volumio[12628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 24 11:01:44 volumio volumio[12628]: info: VolumeController:: Volume=86 Mute =false Feb 24 11:01:44 volumio volumio[12628]: info: CoreStateMachine::pushState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioPushState Feb 24 11:01:44 volumio volumio[12628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 24 11:01:44 volumio volumio[12628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 24 11:01:44 volumio volumio[12628]: info: Received Get System Info Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:01:44 volumio volumio[12628]: info: Discovery: Getting this device information Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:01:44 volumio volumio[12718]: Starting albumart workers Feb 24 11:01:44 volumio volumio[12628]: info: Discovery: adding 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 24 11:01:44 volumio volumio[12628]: info: Discovery: Found device Volumio Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 11:01:44 volumio volumio[12628]: info: Completed loading Core Plugins Feb 24 11:01:44 volumio volumio[12628]: info: Preparing to generate the ALSA configuration file Feb 24 11:01:44 volumio volumio[12628]: info: Discovery: this is already registered, 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 24 11:01:44 volumio volumio[12628]: info: Discovery: Found device Volumio Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:44 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:44 volumio go-librespot[12695]: time="2026-02-24T11:01:44+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 24 11:01:44 volumio go-librespot[12695]: time="2026-02-24T11:01:44+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:54500->104.199.65.9:80: read: connection reset by peer" Feb 24 11:01:44 volumio volumio[12628]: info: Asound.conf file unchanged, so no further update is needed Feb 24 11:01:44 volumio volumio[12628]: info: Output device has changed, restarting MPD Feb 24 11:01:44 volumio volumio[12628]: info: Output device has changed, restarting Shairport Sync Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:44 volumio sudo[12767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 11:01:44 volumio sudo[12767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:44 volumio sudo[12768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 11:01:44 volumio sudo[12768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:44 volumio sudo[12767]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:44 volumio volumio[12628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:01:44 volumio volumio[12628]: info: ___________ START PLUGINS ___________ Feb 24 11:01:44 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 24 11:01:44 volumio volumio[12628]: info: ControllerMpd::onStart: Initializing MPD Feb 24 11:01:44 volumio volumio[12628]: info: Creating MPD Configuration file Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:44 volumio volumio[12628]: info: [1771927304977] CoreMusicLibrary::Adding element Server multimediali Feb 24 11:01:44 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:44 volumio sudo[12778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 24 11:01:44 volumio sudo[12778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 11:01:45 volumio sudo[12780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 11:01:45 volumio volumio[12628]: info: UPNP Browser: Client initialized successfully Feb 24 11:01:45 volumio sudo[12780]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:45 volumio sudo[12781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12778]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:45 volumio volumio[12628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:45 volumio volumio[12628]: info: [1771927305158] CoreMusicLibrary::Adding element Last_100 Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:45 volumio volumio[12628]: info: [1771927305165] CoreMusicLibrary::Adding element Webradio Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:45 volumio volumio[12628]: info: Initializing BBC Radios Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:01:45 volumio volumio[12628]: info: [1771927305288] CoreMusicLibrary::Adding element SoundCloud Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:01:45 volumio volumio[12628]: Cannot find translation for source SoundCloud Feb 24 11:01:45 volumio volumio[12628]: info: Creating Spotify config file Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=debug msg="completed challenge" Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:45 volumio volumio[12628]: info: Loading i18n strings for locale it Feb 24 11:01:45 volumio volumio[12628]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:01:45 volumio volumio[12628]: info: Volumio Calling Home Feb 24 11:01:45 volumio sudo[12826]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 24 11:01:45 volumio sudo[12826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 24 11:01:45 volumio sudo[12826]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:45 volumio sudo[12831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 24 11:01:45 volumio sudo[12833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 24 11:01:45 volumio sudo[12835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio systemd[1]: Reloading. Feb 24 11:01:45 volumio volumio[12628]: info: touch_display: No backlight interface detected. Feb 24 11:01:45 volumio volumio[12628]: info: MPD Permissions set Feb 24 11:01:45 volumio volumio[12628]: info: MPD Permissions set Feb 24 11:01:45 volumio volumio[12628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 24 11:01:45 volumio volumio[12628]: info: Spotify config file written Feb 24 11:01:45 volumio sudo[12841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 24 11:01:45 volumio sudo[12841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio sudo[12841]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:45 volumio sudo[12846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 24 11:01:45 volumio sudo[12846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: No need to fix Spotify hosts Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:45 volumio go-librespot[12695]: time="2026-02-24T11:01:45+01:00" level=debug msg="completed challenge" Feb 24 11:01:46 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:01:46 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:01:46 volumio go-librespot[12695]: time="2026-02-24T11:01:46+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:46 volumio go-librespot[12695]: time="2026-02-24T11:01:46+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:46 volumio volumio[12628]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 24 11:01:46 volumio volumio[12628]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 24 11:01:46 volumio volumio[12628]: info: Volumio called home Feb 24 11:01:46 volumio volumio[12628]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 24 11:01:46 volumio sudo[12877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 24 11:01:46 volumio sudo[12877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:46 volumio volumio[12628]: info: Starting Shairport Sync Feb 24 11:01:46 volumio sudo[12879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 24 11:01:46 volumio sudo[12879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:46 volumio sudo[12877]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:46 volumio sudo[12879]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:46 volumio volumio[12628]: info: Starting Shairport Sync Feb 24 11:01:46 volumio volumio[12628]: info: Starting Shairport Sync Feb 24 11:01:46 volumio sudo[12883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:01:46 volumio sudo[12883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:46 volumio volumio[12628]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 24 11:01:46 volumio sudo[12885]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:01:46 volumio sudo[12885]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:46 volumio sudo[12887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:01:46 volumio sudo[12887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:46 volumio volumio[12628]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 24 11:01:46 volumio volumio[12628]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 24 11:01:46 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:46 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:46 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:46 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:46 volumio volumio[12628]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 11:01:46 volumio volumio[12628]: info: Listing playlists Feb 24 11:01:47 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:47 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:47 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:47 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:47 volumio sudo[12835]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:47 volumio volumio[12628]: info: touch_display: systemctl daemon-reload succeeded. Feb 24 11:01:47 volumio systemd[1]: Reloading. Feb 24 11:01:47 volumio sudo[12894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 24 11:01:47 volumio sudo[12894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:48 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:48 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:48 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:01:48 volumio sudo[12833]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 24 11:01:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 11:01:48 volumio systemd[1]: mpd.service: Consumed 4.007s CPU time. Feb 24 11:01:48 volumio volumio[12628]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 24 11:01:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 24 11:01:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 11:01:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 11:01:48 volumio volumio[12628]: error: Upnp client error: Error: read ECONNRESET Feb 24 11:01:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 11:01:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:48 volumio sudo[12846]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 11:01:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 11:01:48 volumio sudo[12894]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio go-librespot[12914]: go-librespot daemon starting... Feb 24 11:01:48 volumio volumio[12628]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 24 11:01:48 volumio volumio[12628]: info: touch_display: Volumio Kiosk started. Feb 24 11:01:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 11:01:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 11:01:48 volumio systemd[1]: shairport-sync.service: Consumed 1.913s CPU time. Feb 24 11:01:48 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:48 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:48 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:48 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:48 volumio sudo[12831]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio volumio[12628]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 24 11:01:48 volumio sudo[12915]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 11:01:48 volumio sudo[12915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 11:01:48 volumio sudo[12915]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 11:01:48 volumio sudo[12885]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio sudo[12887]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio sudo[12883]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:48 volumio volumio[12628]: info: Shairport-Sync Started Feb 24 11:01:48 volumio volumio[12628]: Error adding Membership: Error: addMembership EINVAL Feb 24 11:01:48 volumio volumio[12628]: info: Shairport-Sync Started Feb 24 11:01:48 volumio volumio[12628]: info: Shairport-Sync Started Feb 24 11:01:49 volumio volumio[12628]: info: touch_display: X display number found: 0 Feb 24 11:01:49 volumio volumio[12628]: info: touch_display: X display number found: 0 Feb 24 11:01:49 volumio volumio[12628]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 24 11:01:49 volumio volumio[12628]: info: touch_display: X display number found: 0 Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=debug msg="app state loaded" Feb 24 11:01:49 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 24. Feb 24 11:01:49 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:49 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:49 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:01:49 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:01:49 volumio volumio[12628]: info: touch_display: Setting screensaver timeout to 200 seconds. Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:49 volumio volumio[12628]: info: No valid Plugin REST Endpoint Feb 24 11:01:49 volumio qobuz-connect[12934]: 20260224 11:01:49.977 [12934.12934] ERROR SampleApp: api request was not successful Feb 24 11:01:49 volumio qobuz-connect[12934]: Error: Application ID was not specified! Feb 24 11:01:49 volumio qobuz-connect[12934]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:49 volumio qobuz-connect[12934]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:49 volumio qobuz-connect[12934]: -n|--deviceName: Device name Feb 24 11:01:49 volumio qobuz-connect[12934]: -l|--model: Device's model name Feb 24 11:01:49 volumio qobuz-connect[12934]: -s|--serialNumber: Device's serial number Feb 24 11:01:49 volumio qobuz-connect[12934]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:49 volumio qobuz-connect[12934]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:49 volumio qobuz-connect[12934]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:49 volumio qobuz-connect[12934]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:49 volumio qobuz-connect[12934]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:49 volumio qobuz-connect[12934]: -h|--help: Print this help Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:49 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:49 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:49 volumio go-librespot[12916]: time="2026-02-24T11:01:49+01:00" level=info msg="zeroconf server listening on port 45731" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="obtained new client token: AAD+odBVPwRrssJz3KDM1hwMVRumWAJKWkXOP3NNDamiSpeMocjB0hAHRc0E+crooUZ+pMO5Ju7PSldtW2asM0TRwxKzCe5dsEU+cyYMSpbGZUepMIe9t8qvwccWq9XJNiefGdIe9tIrL2nH6j8rL0wPfyXOFqZi0LOuS63o8zcvTLj8eRjiiXDIwSj9PwZ8bVa8HHALU5c6uaSfO+ciU0cacjMYpTiIA0fCn3/BcGtcMnYAc01R" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="completed challenge" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:50 volumio sudo[12968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:01:50 volumio sudo[12968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:50 volumio sudo[12970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:01:50 volumio sudo[12970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:50 volumio sudo[12968]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:50 volumio sudo[12970]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:50 volumio sudo[12974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 11:01:50 volumio sudo[12974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:01:50 volumio sudo[12974]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:50 volumio volumio[12628]: info: Upmpdcli Daemon Started Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=debug msg="completed challenge" Feb 24 11:01:50 volumio go-librespot[12916]: time="2026-02-24T11:01:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:51 volumio go-librespot[12916]: time="2026-02-24T11:01:51+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 24 11:01:51 volumio go-librespot[12916]: time="2026-02-24T11:01:51+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:51 volumio go-librespot[12916]: time="2026-02-24T11:01:51+01:00" level=debug msg="completed challenge" Feb 24 11:01:51 volumio go-librespot[12916]: time="2026-02-24T11:01:51+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:51 volumio volumio[12628]: info: go-librespot daemon successfully initialized Feb 24 11:01:52 volumio go-librespot[12916]: time="2026-02-24T11:01:52+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 24 11:01:52 volumio go-librespot[12916]: time="2026-02-24T11:01:52+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:52 volumio go-librespot[12916]: time="2026-02-24T11:01:52+01:00" level=debug msg="completed challenge" Feb 24 11:01:52 volumio go-librespot[12916]: time="2026-02-24T11:01:52+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:53 volumio go-librespot[12916]: time="2026-02-24T11:01:53+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:01:53 volumio mpd[12923]: 2026-02-24T11:01:53 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 11:01:53 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 24 11:01:53 volumio sudo[12768]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:53 volumio sudo[12781]: pam_unix(sudo:session): session closed for user root Feb 24 11:01:53 volumio volumio[12628]: info: Completed starting Core Plugins Feb 24 11:01:53 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:53 volumio volumio[12628]: info: ----- MyVolumio plugins startup ---- Feb 24 11:01:53 volumio volumio[12628]: info: ------------------------------------------- Feb 24 11:01:53 volumio volumio[12628]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 11:01:53 volumio go-librespot[12916]: time="2026-02-24T11:01:53+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:44148->104.199.241.202:80: read: connection reset by peer" Feb 24 11:01:54 volumio go-librespot[12916]: time="2026-02-24T11:01:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:54 volumio go-librespot[12916]: time="2026-02-24T11:01:54+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:54 volumio go-librespot[12916]: time="2026-02-24T11:01:54+01:00" level=debug msg="completed challenge" Feb 24 11:01:54 volumio go-librespot[12916]: time="2026-02-24T11:01:54+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:54 volumio go-librespot[12916]: time="2026-02-24T11:01:54+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:01:54 volumio volumio[12628]: error: MPD error: The expression evaluated to a falsy value: Feb 24 11:01:54 volumio volumio[12628]: assert.ok(self.idling) Feb 24 11:01:54 volumio volumio[12628]: error: The expression evaluated to a falsy value: Feb 24 11:01:54 volumio volumio[12628]: assert.ok(self.idling) Feb 24 11:01:54 volumio volumio[12628]: error: updateQueue error: null Feb 24 11:01:54 volumio volumio[12628]: info: MPD running with PID12923 Feb 24 11:01:54 volumio volumio[12628]: ,establishing connection Feb 24 11:01:54 volumio volumio[12628]: error: updateQueue error: null Feb 24 11:01:54 volumio volumio[12628]: info: Initializing connection to go-librespot Websocket Feb 24 11:01:54 volumio volumio[12628]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:01:55 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 25. Feb 24 11:01:55 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:55 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:01:55 volumio volumio[12628]: info: No valid Plugin REST Endpoint Feb 24 11:01:55 volumio qobuz-connect[12994]: 20260224 11:01:55.323 [12994.12994] ERROR SampleApp: api request was not successful Feb 24 11:01:55 volumio qobuz-connect[12994]: Error: Application ID was not specified! Feb 24 11:01:55 volumio qobuz-connect[12994]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:01:55 volumio qobuz-connect[12994]: -m|--manufacturer: Manufacturer of the device Feb 24 11:01:55 volumio qobuz-connect[12994]: -n|--deviceName: Device name Feb 24 11:01:55 volumio qobuz-connect[12994]: -l|--model: Device's model name Feb 24 11:01:55 volumio qobuz-connect[12994]: -s|--serialNumber: Device's serial number Feb 24 11:01:55 volumio qobuz-connect[12994]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:01:55 volumio qobuz-connect[12994]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:01:55 volumio qobuz-connect[12994]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:01:55 volumio qobuz-connect[12994]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:01:55 volumio qobuz-connect[12994]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:01:55 volumio qobuz-connect[12994]: -h|--help: Print this help Feb 24 11:01:55 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:01:55 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:01:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 24 11:01:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:01:57 volumio go-librespot[12995]: go-librespot daemon starting... Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="app state loaded" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=info msg="zeroconf server listening on port 44335" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="obtained new client token: AAB/sQYzgdnfDx8GoBlteSUYjoahoexBWao+BP8JBMT2G9l6IYbQf0oLBAnILV+Zkuboz4WGbBjlO9qU9Jnf1qslpaURDBJeuTuFi0XdHc3hch9uUKBiFZeut34bpBr38lJNX18d+AgXhywJ29BZfAJolZHA7aKBlRbuqvfvx7vDg+5mY+PdHSKYcFOn5Wpg7Upy3HzG8gf3fyOo2zlHW1X2FYt/E5nK+piLF08ICD6LojRDotfkkoY=" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="completed challenge" Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:57 volumio volumio[12628]: info: Initializing connection to go-librespot Websocket Feb 24 11:01:57 volumio go-librespot[12996]: time="2026-02-24T11:01:57+01:00" level=debug msg="new websocket client" Feb 24 11:01:57 volumio volumio[12628]: info: Connection to go-librespot Websocket established Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=debug msg="completed challenge" Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:58 volumio volumio[12628]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=debug msg="completed challenge" Feb 24 11:01:58 volumio go-librespot[12996]: time="2026-02-24T11:01:58+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:01:59 volumio go-librespot[12996]: time="2026-02-24T11:01:59+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:01:59 volumio go-librespot[12996]: time="2026-02-24T11:01:59+01:00" level=debug msg="completed keyexchange" Feb 24 11:01:59 volumio go-librespot[12996]: time="2026-02-24T11:01:59+01:00" level=debug msg="completed challenge" Feb 24 11:01:59 volumio go-librespot[12996]: time="2026-02-24T11:01:59+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:00 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 26. Feb 24 11:02:00 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:00 volumio go-librespot[12996]: time="2026-02-24T11:02:00+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 11:02:00 volumio go-librespot[12996]: time="2026-02-24T11:02:00+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:00 volumio go-librespot[12996]: time="2026-02-24T11:02:00+01:00" level=debug msg="completed challenge" Feb 24 11:02:00 volumio go-librespot[12996]: time="2026-02-24T11:02:00+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:00 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:00 volumio volumio[12628]: info: No valid Plugin REST Endpoint Feb 24 11:02:00 volumio qobuz-connect[13019]: 20260224 11:02:00.633 [13019.13019] ERROR SampleApp: api request was not successful Feb 24 11:02:00 volumio qobuz-connect[13019]: Error: Application ID was not specified! Feb 24 11:02:00 volumio qobuz-connect[13019]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:02:00 volumio qobuz-connect[13019]: -m|--manufacturer: Manufacturer of the device Feb 24 11:02:00 volumio qobuz-connect[13019]: -n|--deviceName: Device name Feb 24 11:02:00 volumio qobuz-connect[13019]: -l|--model: Device's model name Feb 24 11:02:00 volumio qobuz-connect[13019]: -s|--serialNumber: Device's serial number Feb 24 11:02:00 volumio qobuz-connect[13019]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:02:00 volumio qobuz-connect[13019]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:02:00 volumio qobuz-connect[13019]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:02:00 volumio qobuz-connect[13019]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:02:00 volumio qobuz-connect[13019]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:02:00 volumio qobuz-connect[13019]: -h|--help: Print this help Feb 24 11:02:00 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:00 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:02:00 volumio volumio[12628]: info: Getting Spotify volume Feb 24 11:02:01 volumio volumio[12628]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 24 11:02:01 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:01 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:01 volumio volumio[12628]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 11:02:01 volumio volumio[12628]: SPOTIFY: SPOTIFY VOLUME undefined Feb 24 11:02:01 volumio volumio[12628]: SPOTIFY: VOLUMIO VOLUME 86 Feb 24 11:02:01 volumio volumio[12628]: info: Aligning Spotify Volume to Volumio Volume Feb 24 11:02:01 volumio volumio[12628]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:01 volumio volumio[12628]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:01 volumio volumio[12628]: info: Setting Spotify Volume from Volumio: 86 Feb 24 11:02:01 volumio go-librespot[12996]: time="2026-02-24T11:02:01+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:02:01 volumio go-librespot[12996]: time="2026-02-24T11:02:01+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:01 volumio go-librespot[12996]: time="2026-02-24T11:02:01+01:00" level=debug msg="completed challenge" Feb 24 11:02:01 volumio go-librespot[12996]: time="2026-02-24T11:02:01+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:01 volumio go-librespot[12996]: time="2026-02-24T11:02:01+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:02:01 volumio volumio[12628]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:02:01 volumio volumio[12628]: Error: socket hang up Feb 24 11:02:01 volumio volumio[12628]: at connResetException (node:internal/errors:720:14) Feb 24 11:02:01 volumio volumio[12628]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 24 11:02:01 volumio volumio[12628]: at Socket.emit (node:events:526:35) Feb 24 11:02:01 volumio volumio[12628]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 24 11:02:01 volumio volumio[12628]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 24 11:02:01 volumio volumio[12628]: code: 'ECONNRESET', Feb 24 11:02:01 volumio volumio[12628]: response: undefined Feb 24 11:02:01 volumio volumio[12628]: } Feb 24 11:02:01 volumio volumio[12628]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:02:03 volumio sudo[13034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 11:01' Feb 24 11:02:03 volumio sudo[13034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:03 volumio sudo[13034]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:03 volumio volumio-remote-updater[715]: [2026-02-24 11:02:03] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 24 11:02:03 volumio volumio-remote-updater[715]: [2026-02-24 11:02:03] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 24 11:02:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 24 11:02:03 volumio systemd[1]: volumio.service: Consumed 18.602s CPU time. Feb 24 11:02:03 volumio volumio5-onboarding[11484]: time=2026-02-24T11:02:03.652+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 24 11:02:03 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 24 11:02:03 volumio volumio5-onboarding[11484]: time=2026-02-24T11:02:03.730+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 24 11:02:03 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 24 11:02:04 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 854. Feb 24 11:02:04 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 24 11:02:04 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 24 11:02:04 volumio systemd[1]: volumio.service: Consumed 18.602s CPU time. Feb 24 11:02:04 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 24 11:02:04 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 24 11:02:04 volumio volumio5-onboarding[11484]: time=2026-02-24T11:02:04.734+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 24 11:02:04 volumio nmbd[1108]: [2026/02/24 11:02:04.963105, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 24 11:02:04 volumio nmbd[1108]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.3 for name WORKGROUP<1d>. Feb 24 11:02:04 volumio nmbd[1108]: This response was from IP 192.168.0.125, reporting an IP address of 192.168.0.125. Feb 24 11:02:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 24 11:02:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:05 volumio go-librespot[13065]: go-librespot daemon starting... Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="app state loaded" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=info msg="zeroconf server listening on port 46869" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="obtained new client token: AAB8Ngy2J06zaplsaQ9FgJQiu0ttwzWwtx1qd09EvUCKcsu2WZE7M0MJfyY/ayxLyHhxLwQZS7Gfdpw8Z6X3E+T5PFyYdSExv337K0408wl0dl/OSzrnAYt4/vxw8n9jOcanvxXA+YEB15RbCstO9i4zrrUNdU8mNsWn4NlJ2+HSC2w8O3DVJYjBHn67k9lmZK65fFNLsoXuHy2IspKsGcX+irPzwAqUqpalVEXHz64IjARAvxsgstA=" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="completed challenge" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:05 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:05 volumio volumio[13049]: info: ----- Volumio3 ---- Feb 24 11:02:05 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:05 volumio volumio[13049]: info: ----- System startup ---- Feb 24 11:02:05 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:05 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 27. Feb 24 11:02:05 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:05 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:05 volumio go-librespot[13066]: time="2026-02-24T11:02:05+01:00" level=debug msg="completed challenge" Feb 24 11:02:06 volumio go-librespot[13066]: time="2026-02-24T11:02:06+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:06 volumio volumio[13049]: info: MYVOLUMIO Environment detected Feb 24 11:02:06 volumio go-librespot[13066]: time="2026-02-24T11:02:06+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Feb 24 11:02:06 volumio volumio[13049]: info: Plugin folders cleanup Feb 24 11:02:06 volumio volumio[13049]: info: Scanning into folder /volumio/app/plugins/ Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category audio_interface Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category miscellanea Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category music_service Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category plugins.json Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category system_controller Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category user_interface Feb 24 11:02:06 volumio volumio[13049]: info: Scanning into folder /data/plugins/ Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category music_service Feb 24 11:02:06 volumio volumio[13049]: info: Scanning category user_interface Feb 24 11:02:06 volumio volumio[13049]: info: Plugin folders cleanup completed Feb 24 11:02:06 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:06 volumio volumio[13049]: info: ----- Core plugins startup ---- Feb 24 11:02:06 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:06 volumio volumio[13049]: info: Loading plugins from folder /volumio/app/plugins/ Feb 24 11:02:06 volumio volumio[13049]: info: Adding plugin upnp to MyMusic Plugins Feb 24 11:02:06 volumio volumio[13049]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 24 11:02:06 volumio volumio[13049]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 24 11:02:06 volumio volumio[13049]: info: Loading plugins from folder /data/plugins/ Feb 24 11:02:06 volumio volumio[13049]: info: Loading plugin "system"... Feb 24 11:02:06 volumio volumio[13049]: info: Loading plugin "appearance"... Feb 24 11:02:06 volumio go-librespot[13066]: time="2026-02-24T11:02:06+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 24 11:02:06 volumio go-librespot[13066]: time="2026-02-24T11:02:06+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:06 volumio go-librespot[13066]: time="2026-02-24T11:02:06+01:00" level=debug msg="completed challenge" Feb 24 11:02:06 volumio go-librespot[13066]: time="2026-02-24T11:02:06+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:07 volumio go-librespot[13066]: time="2026-02-24T11:02:07+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "network"... Feb 24 11:02:07 volumio go-librespot[13066]: time="2026-02-24T11:02:07+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:07 volumio go-librespot[13066]: time="2026-02-24T11:02:07+01:00" level=debug msg="completed challenge" Feb 24 11:02:07 volumio volumio[13049]: info: Refreshing Cached IP Addresses Feb 24 11:02:07 volumio sudo[13089]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:02:07 volumio sudo[13089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:07 volumio sudo[13091]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:02:07 volumio sudo[13091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:07 volumio sudo[13089]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "services"... Feb 24 11:02:07 volumio sudo[13091]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "volumio5onboarding"... Feb 24 11:02:07 volumio sudo[13099]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 24 11:02:07 volumio sudo[13099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "alsa_controller"... Feb 24 11:02:07 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:02:07 volumio go-librespot[13066]: time="2026-02-24T11:02:07+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "wizard"... Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "networkfs"... Feb 24 11:02:07 volumio volumio[13049]: info: Starting Udev Watcher for removable devices Feb 24 11:02:07 volumio volumio[13049]: info: Ignoring mount for partition: boot Feb 24 11:02:07 volumio volumio[13049]: info: Ignoring mount for partition: volumio Feb 24 11:02:07 volumio volumio[13049]: info: Ignoring mount for partition: volumio_data Feb 24 11:02:07 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "volumio_command_line_client"... Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "upnp"... Feb 24 11:02:07 volumio volumio[13049]: info: [1771927327876] Starting Upmpd Daemon Feb 24 11:02:07 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "my_music"... Feb 24 11:02:07 volumio volumio[13049]: info: Loading plugin "mpd"... Feb 24 11:02:08 volumio volumio[13049]: info: Loading plugin "upnp_browser"... Feb 24 11:02:08 volumio go-librespot[13066]: time="2026-02-24T11:02:08+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:02:08 volumio volumio-remote-updater[715]: [2026-02-24 11:02:08] [connect] Successful connection Feb 24 11:02:08 volumio go-librespot[13066]: time="2026-02-24T11:02:08+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 24 11:02:09 volumio volumio[13049]: info: Starting UPNP Browser Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "alarm-clock"... Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "airplay_emulation"... Feb 24 11:02:09 volumio volumio[13049]: info: Starting Shairport Sync Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "last_100"... Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "webradio"... Feb 24 11:02:09 volumio go-librespot[13066]: time="2026-02-24T11:02:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "i2s_dacs"... Feb 24 11:02:09 volumio volumio[13049]: info: I2S DAC not set, start Auto-detection Feb 24 11:02:09 volumio go-librespot[13066]: time="2026-02-24T11:02:09+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:09 volumio go-librespot[13066]: time="2026-02-24T11:02:09+01:00" level=debug msg="completed challenge" Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "volumiodiscovery"... Feb 24 11:02:09 volumio go-librespot[13066]: time="2026-02-24T11:02:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:09 volumio go-librespot[13066]: time="2026-02-24T11:02:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:09 volumio volumio[13049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 11:02:09 volumio volumio[13049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:02:09 volumio volumio[13049]: *** WARNING *** For more information see Feb 24 11:02:09 volumio node[13049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 24 11:02:09 volumio volumio[13049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 11:02:09 volumio volumio[13049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:02:09 volumio volumio[13049]: *** WARNING *** For more information see Feb 24 11:02:09 volumio node[13049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:02:09 volumio node[13049]: *** WARNING *** For more information see Feb 24 11:02:09 volumio node[13049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 24 11:02:09 volumio node[13049]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 24 11:02:09 volumio node[13049]: *** WARNING *** For more information see Feb 24 11:02:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:02:09 volumio volumio[13049]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 24 11:02:09 volumio volumio[13049]: info: Discovery: Started advertising with name: Volumio Feb 24 11:02:09 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "soundcloud"... Feb 24 11:02:09 volumio volumio[13049]: info: Loading plugin "spop"... Feb 24 11:02:10 volumio sudo[13099]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "outputs"... Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "albumart"... Feb 24 11:02:10 volumio volumio[13049]: info: Plugin example_plugin is not enabled Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "inputs"... Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "updater_comm"... Feb 24 11:02:10 volumio volumio[13049]: info: Plugin mpdemulation is not enabled Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "rest_api"... Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "websocket"... Feb 24 11:02:10 volumio volumio[13049]: info: Starting Socket.io Server version 1.7.4 Feb 24 11:02:10 volumio volumio[13049]: info: Loading plugin "touch_display"... Feb 24 11:02:10 volumio volumio[13049]: info: Applying required configuration parameters for plugin touch_display Feb 24 11:02:10 volumio volumio[13124]: Forking 3 albumart workers Feb 24 11:02:11 volumio volumio[13049]: info: Loading i18n strings for locale it Feb 24 11:02:11 volumio volumio[13049]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 11:02:11 volumio volumio[13049]: Updating browse sources language Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::initPlayerControls Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: Express server listening on port 3000 Feb 24 11:02:11 volumio volumio[13049]: [Metrics] WebUI: 5s 970.08ms Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::resetVolumioState Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::getcurrentVolume Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: Volumio Network Manager: Network status updated: 1 Feb 24 11:02:11 volumio volumio[13049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 24 11:02:11 volumio volumio[13049]: info: VolumeController:: Volume=86 Mute =false Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::pushState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioPushState Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::updateTrackBlock Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrackBlock Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioRetrievevolume Feb 24 11:02:11 volumio volumio[13049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 24 11:02:11 volumio volumio[13049]: info: Received Get System Info Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:02:11 volumio volumio[13049]: info: Discovery: Getting this device information Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:02:11 volumio volumio[13049]: info: No valid Plugin REST Endpoint Feb 24 11:02:11 volumio qobuz-connect[13081]: 20260224 11:02:11.571 [13081.13081] ERROR SampleApp: api request was not successful Feb 24 11:02:11 volumio qobuz-connect[13081]: Error: Application ID was not specified! Feb 24 11:02:11 volumio qobuz-connect[13081]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:02:11 volumio qobuz-connect[13081]: -m|--manufacturer: Manufacturer of the device Feb 24 11:02:11 volumio qobuz-connect[13081]: -n|--deviceName: Device name Feb 24 11:02:11 volumio qobuz-connect[13081]: -l|--model: Device's model name Feb 24 11:02:11 volumio qobuz-connect[13081]: -s|--serialNumber: Device's serial number Feb 24 11:02:11 volumio qobuz-connect[13081]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:02:11 volumio qobuz-connect[13081]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:02:11 volumio qobuz-connect[13081]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:02:11 volumio qobuz-connect[13081]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:02:11 volumio qobuz-connect[13081]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:02:11 volumio qobuz-connect[13081]: -h|--help: Print this help Feb 24 11:02:11 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:11 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:02:11 volumio volumio[13049]: info: Reloading queue from file Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 24 11:02:11 volumio volumio[13049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::setRepeat null single undefined Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::pushState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioPushState Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::setRandom false Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::pushState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioPushState Feb 24 11:02:11 volumio volumio[13049]: info: Setting Device type: Raspberry PI Feb 24 11:02:11 volumio volumio-remote-updater[715]: [2026-02-24 11:02:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771927328 101 Feb 24 11:02:11 volumio volumio[13049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Feb 24 11:02:11 volumio volumio[13049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 24 11:02:11 volumio volumio[13049]: info: VolumeController:: Volume=86 Mute =false Feb 24 11:02:11 volumio volumio[13049]: info: CoreStateMachine::pushState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioPushState Feb 24 11:02:11 volumio volumio[13049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 24 11:02:11 volumio volumio[13049]: info: Discovery: adding 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 24 11:02:11 volumio volumio[13049]: info: Discovery: Found device Volumio Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13049]: info: Completed loading Core Plugins Feb 24 11:02:11 volumio volumio[13049]: info: Preparing to generate the ALSA configuration file Feb 24 11:02:11 volumio volumio[13049]: info: Discovery: this is already registered, 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 24 11:02:11 volumio volumio[13049]: info: Discovery: Found device Volumio Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:11 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:11 volumio volumio[13136]: Starting albumart workers Feb 24 11:02:11 volumio volumio[13049]: info: Asound.conf file unchanged, so no further update is needed Feb 24 11:02:11 volumio volumio[13049]: info: Output device has changed, restarting MPD Feb 24 11:02:11 volumio sudo[13184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 11:02:11 volumio volumio[13049]: info: Output device has changed, restarting Shairport Sync Feb 24 11:02:11 volumio sudo[13184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:11 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:11 volumio sudo[13184]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:11 volumio volumio[13049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:02:12 volumio volumio[13049]: info: ___________ START PLUGINS ___________ Feb 24 11:02:12 volumio sudo[13188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 11:02:12 volumio volumio[13049]: info: ControllerMpd::onStart: Initializing MPD Feb 24 11:02:12 volumio volumio[13049]: info: Creating MPD Configuration file Feb 24 11:02:12 volumio sudo[13188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio volumio[13134]: Starting albumart workers Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:02:12 volumio volumio[13049]: info: [1771927332058] CoreMusicLibrary::Adding element Server multimediali Feb 24 11:02:12 volumio volumio[13135]: Starting albumart workers Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:02:12 volumio sudo[13193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 24 11:02:12 volumio sudo[13193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio sudo[13196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 11:02:12 volumio sudo[13196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio sudo[13198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 11:02:12 volumio sudo[13196]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:12 volumio sudo[13198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio volumio[13049]: info: UPNP Browser: Client initialized successfully Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:12 volumio volumio[13049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 24 11:02:12 volumio volumio[13049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 11:02:12 volumio sudo[13193]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:02:12 volumio volumio[13049]: info: [1771927332221] CoreMusicLibrary::Adding element Last_100 Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:02:12 volumio volumio[13049]: info: [1771927332224] CoreMusicLibrary::Adding element Webradio Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:02:12 volumio volumio[13049]: info: Initializing BBC Radios Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 24 11:02:12 volumio volumio[13049]: info: [1771927332435] CoreMusicLibrary::Adding element SoundCloud Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:02:12 volumio volumio[13049]: Cannot find translation for source SoundCloud Feb 24 11:02:12 volumio volumio[13049]: info: Creating Spotify config file Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 24 11:02:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:12 volumio go-librespot[13226]: go-librespot daemon starting... Feb 24 11:02:12 volumio go-librespot[13230]: time="2026-02-24T11:02:12+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:02:12 volumio volumio[13049]: info: Loading i18n strings for locale it Feb 24 11:02:12 volumio volumio[13049]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 24 11:02:12 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:02:12 volumio volumio[13049]: info: Volumio Calling Home Feb 24 11:02:12 volumio sudo[13250]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 24 11:02:12 volumio sudo[13250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio sudo[13256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 24 11:02:12 volumio sudo[13256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio sudo[13258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 24 11:02:12 volumio sudo[13258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio sudo[13250]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:12 volumio sudo[13260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 24 11:02:12 volumio sudo[13260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:12 volumio go-librespot[13230]: time="2026-02-24T11:02:12+01:00" level=info msg="zeroconf server listening on port 39263" Feb 24 11:02:12 volumio systemd[1]: Reloading. Feb 24 11:02:13 volumio volumio[13049]: info: touch_display: No backlight interface detected. Feb 24 11:02:13 volumio volumio[13049]: info: MPD Permissions set Feb 24 11:02:13 volumio volumio[13049]: info: MPD Permissions set Feb 24 11:02:13 volumio sudo[13268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 24 11:02:13 volumio sudo[13268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio sudo[13268]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:13 volumio volumio[13049]: info: Spotify config file written Feb 24 11:02:13 volumio volumio[13049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 24 11:02:13 volumio volumio[13049]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 24 11:02:13 volumio volumio[13049]: info: No need to fix Spotify hosts Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio sudo[13288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 24 11:02:13 volumio sudo[13288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:02:13 volumio volumio[13049]: info: Volumio called home Feb 24 11:02:13 volumio volumio[13049]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 24 11:02:13 volumio volumio[13049]: info: Starting Shairport Sync Feb 24 11:02:13 volumio volumio[13049]: info: Starting Shairport Sync Feb 24 11:02:13 volumio volumio[13049]: info: Starting Shairport Sync Feb 24 11:02:13 volumio volumio[13049]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 24 11:02:13 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:13 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:13 volumio sudo[13307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:02:13 volumio sudo[13307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio sudo[13305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 24 11:02:13 volumio sudo[13303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 24 11:02:13 volumio sudo[13303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio sudo[13311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:02:13 volumio sudo[13311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio sudo[13305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio sudo[13309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 11:02:13 volumio sudo[13309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:13 volumio sudo[13303]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:13 volumio sudo[13305]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:13 volumio volumio[13049]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 24 11:02:13 volumio volumio[13049]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 24 11:02:14 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:14 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:14 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:14 volumio sudo[13260]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:14 volumio volumio[13049]: info: touch_display: systemctl daemon-reload succeeded. Feb 24 11:02:14 volumio sudo[13319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 24 11:02:14 volumio sudo[13319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:14 volumio systemd[1]: Reloading. Feb 24 11:02:15 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:15 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:15 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 24 11:02:15 volumio sudo[13258]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 24 11:02:15 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 24 11:02:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 11:02:15 volumio systemd[1]: mpd.service: Consumed 3.832s CPU time. Feb 24 11:02:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 24 11:02:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 11:02:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 11:02:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 11:02:15 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 24 11:02:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 11:02:15 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 11:02:15 volumio sudo[13319]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: Volumio Kiosk started. Feb 24 11:02:15 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 11:02:15 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 11:02:15 volumio systemd[1]: shairport-sync.service: Consumed 1.931s CPU time. Feb 24 11:02:15 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:15 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:15 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 24 11:02:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:15 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:15 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:15 volumio sudo[13256]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: X display number found: 0 Feb 24 11:02:15 volumio sudo[13340]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 11:02:15 volumio sudo[13340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 24 11:02:15 volumio sudo[13340]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:15 volumio sudo[13288]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 11:02:15 volumio go-librespot[13343]: go-librespot daemon starting... Feb 24 11:02:15 volumio sudo[13311]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio sudo[13309]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio sudo[13307]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:15 volumio volumio[13049]: info: Shairport-Sync Started Feb 24 11:02:15 volumio volumio[13049]: Error adding Membership: Error: addMembership EINVAL Feb 24 11:02:15 volumio volumio[13049]: info: Shairport-Sync Started Feb 24 11:02:15 volumio volumio[13049]: info: Shairport-Sync Started Feb 24 11:02:15 volumio go-librespot[13350]: time="2026-02-24T11:02:15+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:02:15 volumio go-librespot[13350]: time="2026-02-24T11:02:15+01:00" level=debug msg="app state loaded" Feb 24 11:02:15 volumio volumio[13049]: info: touch_display: X display number found: 0 Feb 24 11:02:15 volumio go-librespot[13350]: time="2026-02-24T11:02:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:02:16 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:16 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:16 volumio volumio[13049]: info: touch_display: Setting screensaver timeout to 200 seconds. Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=info msg="zeroconf server listening on port 43385" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="obtained new client token: AAAn39Dv4oEZfyGFlXmy1DoKjbuoe0ElPyIkZNy5Da8JJVQN2TutTkl1WbS8KvHhsU4r+PIDCwzrXQ5qyWmfL8Ndcmj17EirAOiZ+s9ObCea53w6Hi4eEur9IDxsOH67/wC/dW5GRZoAzHwnuNcycL25/tjiinWl7RSXXREfh+uSochWtRF9hVhQLdaecXCo37Xn365IXihpw5Z4InXstm/A1P+cGgStuz+a7YdsfWBP12IgrAyW" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="completed challenge" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:16 volumio volumio[13049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 24 11:02:16 volumio volumio[13049]: info: CoreCommandRouter::volumioGetState Feb 24 11:02:16 volumio volumio[13049]: info: CorePlayQueue::getTrack 0 Feb 24 11:02:16 volumio volumio[13049]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 11:02:16 volumio volumio[13049]: info: Listing playlists Feb 24 11:02:16 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 28. Feb 24 11:02:16 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:16 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=debug msg="completed challenge" Feb 24 11:02:16 volumio go-librespot[13350]: time="2026-02-24T11:02:16+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:16 volumio volumio[13049]: info: No valid Plugin REST Endpoint Feb 24 11:02:16 volumio qobuz-connect[13379]: 20260224 11:02:16.959 [13379.13379] ERROR SampleApp: api request was not successful Feb 24 11:02:16 volumio qobuz-connect[13379]: Error: Application ID was not specified! Feb 24 11:02:16 volumio qobuz-connect[13379]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:02:16 volumio qobuz-connect[13379]: -m|--manufacturer: Manufacturer of the device Feb 24 11:02:16 volumio qobuz-connect[13379]: -n|--deviceName: Device name Feb 24 11:02:16 volumio qobuz-connect[13379]: -l|--model: Device's model name Feb 24 11:02:16 volumio qobuz-connect[13379]: -s|--serialNumber: Device's serial number Feb 24 11:02:16 volumio qobuz-connect[13379]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:02:16 volumio qobuz-connect[13379]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:02:16 volumio qobuz-connect[13379]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:02:16 volumio qobuz-connect[13379]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:02:16 volumio qobuz-connect[13379]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:02:16 volumio qobuz-connect[13379]: -h|--help: Print this help Feb 24 11:02:16 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:16 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:02:17 volumio go-librespot[13350]: time="2026-02-24T11:02:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:02:17 volumio go-librespot[13350]: time="2026-02-24T11:02:17+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:17 volumio go-librespot[13350]: time="2026-02-24T11:02:17+01:00" level=debug msg="completed challenge" Feb 24 11:02:17 volumio go-librespot[13350]: time="2026-02-24T11:02:17+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:17 volumio sudo[13395]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:02:17 volumio sudo[13395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:17 volumio sudo[13395]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:17 volumio sudo[13397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:02:17 volumio sudo[13397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:17 volumio sudo[13397]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:17 volumio sudo[13401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 24 11:02:17 volumio sudo[13401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 11:02:17 volumio sudo[13401]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:17 volumio volumio[13049]: info: Upmpdcli Daemon Started Feb 24 11:02:18 volumio go-librespot[13350]: time="2026-02-24T11:02:18+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 24 11:02:18 volumio go-librespot[13350]: time="2026-02-24T11:02:18+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:18 volumio go-librespot[13350]: time="2026-02-24T11:02:18+01:00" level=debug msg="completed challenge" Feb 24 11:02:18 volumio go-librespot[13350]: time="2026-02-24T11:02:18+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:18 volumio volumio[13049]: info: go-librespot daemon successfully initialized Feb 24 11:02:19 volumio go-librespot[13350]: time="2026-02-24T11:02:19+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 24 11:02:19 volumio go-librespot[13350]: time="2026-02-24T11:02:19+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:19 volumio go-librespot[13350]: time="2026-02-24T11:02:19+01:00" level=debug msg="completed challenge" Feb 24 11:02:19 volumio go-librespot[13350]: time="2026-02-24T11:02:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:19 volumio mpd[13351]: 2026-02-24T11:02:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 11:02:19 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 24 11:02:19 volumio sudo[13188]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:19 volumio sudo[13198]: pam_unix(sudo:session): session closed for user root Feb 24 11:02:19 volumio volumio[13049]: info: Completed starting Core Plugins Feb 24 11:02:19 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:19 volumio volumio[13049]: info: ----- MyVolumio plugins startup ---- Feb 24 11:02:19 volumio volumio[13049]: info: ------------------------------------------- Feb 24 11:02:19 volumio volumio[13049]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 24 11:02:19 volumio volumio[13049]: error: MPD error: The expression evaluated to a falsy value: Feb 24 11:02:19 volumio volumio[13049]: assert.ok(self.idling) Feb 24 11:02:19 volumio volumio[13049]: error: The expression evaluated to a falsy value: Feb 24 11:02:19 volumio volumio[13049]: assert.ok(self.idling) Feb 24 11:02:19 volumio volumio[13049]: error: updateQueue error: null Feb 24 11:02:19 volumio volumio[13049]: info: MPD running with PID13351 Feb 24 11:02:19 volumio volumio[13049]: ,establishing connection Feb 24 11:02:19 volumio volumio[13049]: error: updateQueue error: null Feb 24 11:02:19 volumio go-librespot[13350]: time="2026-02-24T11:02:19+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 24 11:02:20 volumio go-librespot[13350]: time="2026-02-24T11:02:20+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:20 volumio go-librespot[13350]: time="2026-02-24T11:02:20+01:00" level=debug msg="completed challenge" Feb 24 11:02:20 volumio go-librespot[13350]: time="2026-02-24T11:02:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:20 volumio go-librespot[13350]: time="2026-02-24T11:02:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:02:21 volumio volumio[13049]: info: Initializing connection to go-librespot Websocket Feb 24 11:02:21 volumio volumio[13049]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:02:22 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 29. Feb 24 11:02:22 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:22 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:22 volumio volumio[13049]: info: No valid Plugin REST Endpoint Feb 24 11:02:22 volumio qobuz-connect[13407]: 20260224 11:02:22.249 [13407.13407] ERROR SampleApp: api request was not successful Feb 24 11:02:22 volumio qobuz-connect[13407]: Error: Application ID was not specified! Feb 24 11:02:22 volumio qobuz-connect[13407]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:02:22 volumio qobuz-connect[13407]: -m|--manufacturer: Manufacturer of the device Feb 24 11:02:22 volumio qobuz-connect[13407]: -n|--deviceName: Device name Feb 24 11:02:22 volumio qobuz-connect[13407]: -l|--model: Device's model name Feb 24 11:02:22 volumio qobuz-connect[13407]: -s|--serialNumber: Device's serial number Feb 24 11:02:22 volumio qobuz-connect[13407]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:02:22 volumio qobuz-connect[13407]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:02:22 volumio qobuz-connect[13407]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:02:22 volumio qobuz-connect[13407]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:02:22 volumio qobuz-connect[13407]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:02:22 volumio qobuz-connect[13407]: -h|--help: Print this help Feb 24 11:02:22 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:22 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:02:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 24 11:02:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 11:02:23 volumio go-librespot[13408]: go-librespot daemon starting... Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=info msg="running go-librespot 0.6.2" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="app state loaded" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=info msg="zeroconf server listening on port 43687" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="obtained new client token: AAAmiTQWO/D/Kt4NvVRrXwa2Tr1haWT3Tjt8GS1GnB4ot5Gf4uWLAMJuR1D1NUQ47rvjxVvcV5gWeAqxG22mYWyBgdRA86EqAahNdg7K847q0XBSJMhQJyTt/NFx+7F0nARMT+LKBjEJPtIFBEQkKWG1DqFzgySC24VcejioOgs125bf12A5rE+O69Y9GTGCvSuVhPBNlRrVWNm9DV8eNn7RByNM+NHE80l6VqdusgCDFZtDCdPzvYo=" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=debug msg="completed challenge" Feb 24 11:02:23 volumio go-librespot[13409]: time="2026-02-24T11:02:23+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="completed challenge" Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:24 volumio volumio[13049]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="completed challenge" Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:24 volumio volumio[13049]: info: Initializing connection to go-librespot Websocket Feb 24 11:02:24 volumio go-librespot[13409]: time="2026-02-24T11:02:24+01:00" level=debug msg="new websocket client" Feb 24 11:02:24 volumio volumio[13049]: info: Connection to go-librespot Websocket established Feb 24 11:02:25 volumio go-librespot[13409]: time="2026-02-24T11:02:25+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 24 11:02:25 volumio go-librespot[13409]: time="2026-02-24T11:02:25+01:00" level=debug msg="completed keyexchange" Feb 24 11:02:25 volumio go-librespot[13409]: time="2026-02-24T11:02:25+01:00" level=debug msg="completed challenge" Feb 24 11:02:25 volumio go-librespot[13409]: time="2026-02-24T11:02:25+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 24 11:02:26 volumio go-librespot[13409]: time="2026-02-24T11:02:26+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 24 11:02:26 volumio go-librespot[13409]: time="2026-02-24T11:02:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 24 11:02:27 volumio go-librespot[13409]: time="2026-02-24T11:02:27+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 24 11:02:27 volumio go-librespot[13409]: time="2026-02-24T11:02:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:53494->104.199.241.202:80: read: connection reset by peer" Feb 24 11:02:27 volumio go-librespot[13409]: time="2026-02-24T11:02:27+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:53494->104.199.241.202:80: read: connection reset by peer" Feb 24 11:02:27 volumio volumio[13049]: info: Connection to go-librespot Websocket closed Feb 24 11:02:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 11:02:27 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 30. Feb 24 11:02:27 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:27 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 24 11:02:27 volumio volumio[13049]: info: No valid Plugin REST Endpoint Feb 24 11:02:27 volumio qobuz-connect[13421]: 20260224 11:02:27.610 [13421.13421] ERROR SampleApp: api request was not successful Feb 24 11:02:27 volumio qobuz-connect[13421]: Error: Application ID was not specified! Feb 24 11:02:27 volumio qobuz-connect[13421]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 24 11:02:27 volumio qobuz-connect[13421]: -m|--manufacturer: Manufacturer of the device Feb 24 11:02:27 volumio qobuz-connect[13421]: -n|--deviceName: Device name Feb 24 11:02:27 volumio qobuz-connect[13421]: -l|--model: Device's model name Feb 24 11:02:27 volumio qobuz-connect[13421]: -s|--serialNumber: Device's serial number Feb 24 11:02:27 volumio qobuz-connect[13421]: -o|--outputDevice: ALSA PCM device to play audio to Feb 24 11:02:27 volumio qobuz-connect[13421]: -p|--port: Port to run the local configuration HTTP server on Feb 24 11:02:27 volumio qobuz-connect[13421]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 24 11:02:27 volumio qobuz-connect[13421]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 24 11:02:27 volumio qobuz-connect[13421]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 24 11:02:27 volumio qobuz-connect[13421]: -h|--help: Print this help Feb 24 11:02:27 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 24 11:02:27 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 24 11:02:27 volumio volumio[13049]: info: Getting Spotify volume Feb 24 11:02:28 volumio volumio[13049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:02:28 volumio volumio[13049]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 11:02:28 volumio volumio[13049]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 24 11:02:28 volumio volumio[13049]: errno: -111, Feb 24 11:02:28 volumio volumio[13049]: code: 'ECONNREFUSED', Feb 24 11:02:28 volumio volumio[13049]: syscall: 'connect', Feb 24 11:02:28 volumio volumio[13049]: address: '127.0.0.1', Feb 24 11:02:28 volumio volumio[13049]: port: 9879, Feb 24 11:02:28 volumio volumio[13049]: response: undefined Feb 24 11:02:28 volumio volumio[13049]: } Feb 24 11:02:28 volumio volumio[13049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:02:29 volumio sudo[13450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 11:01' Feb 24 11:02:29 volumio sudo[13450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"