Feb 12 14:41:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 12 14:41:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:01 volumio go-librespot[12318]: go-librespot daemon starting... Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=debug msg="app state loaded" Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:01 volumio volumio-remote-updater[643]: [2026-02-12 14:41:01] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 12 14:41:01 volumio volumio-remote-updater[643]: [2026-02-12 14:41:01] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 12 14:41:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 14:41:01 volumio systemd[1]: volumio.service: Consumed 55.902s CPU time. Feb 12 14:41:01 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 14:41:01 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 14:41:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13311. Feb 12 14:41:01 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 14:41:01 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 14:41:01 volumio systemd[1]: volumio.service: Consumed 55.902s CPU time. Feb 12 14:41:01 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:01 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=info msg="zeroconf server listening on port 38857" Feb 12 14:41:01 volumio go-librespot[12319]: time="2026-02-12T14:41:01+07:00" level=debug msg="obtained new client token: AACc5uUn3DhSNOvaPS6a7w4ykP6xSlcB+Y1wrZKcPH9T9idkDc+Z4cN12vABZ03CYWg3IjTI7AeBL6Ctxrm80GrvaW9wdmH+3BIcELwFENfeyaQ9c+t1ZWNAMvMCd6LAy26aPrMo74vLaxXBkGoCn2LOoji/gKLkyuIl4QGNr2v9jeb/FCQ4FIzScAhVVV7Z9J3VsC0dxs8UGpRfNNAlJoP9fbHTYmvXC1qV8rc6b4fIUUvTP9O74mQXGQ==" Feb 12 14:41:02 volumio go-librespot[12319]: time="2026-02-12T14:41:02+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 12 14:41:02 volumio go-librespot[12319]: time="2026-02-12T14:41:02+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 12 14:41:02 volumio go-librespot[12319]: time="2026-02-12T14:41:02+07:00" level=debug msg="completed keyexchange" Feb 12 14:41:02 volumio go-librespot[12319]: time="2026-02-12T14:41:02+07:00" level=debug msg="completed challenge" Feb 12 14:41:02 volumio go-librespot[12319]: time="2026-02-12T14:41:02+07: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 12 14:41:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:05 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:05 volumio volumio[12339]: info: ----- Volumio3 ---- Feb 12 14:41:05 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:05 volumio volumio[12339]: info: ----- System startup ---- Feb 12 14:41:05 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 12 14:41:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:05 volumio go-librespot[12360]: go-librespot daemon starting... Feb 12 14:41:05 volumio go-librespot[12361]: time="2026-02-12T14:41:05+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:05 volumio go-librespot[12361]: time="2026-02-12T14:41:05+07:00" level=debug msg="app state loaded" Feb 12 14:41:05 volumio go-librespot[12361]: time="2026-02-12T14:41:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=info msg="zeroconf server listening on port 42969" Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=debug msg="obtained new client token: AAAV3aKg+e1WjdkSjrGcm4qOdR48kOUpCa4KN2Iz5FKMKVjAOx8ypsuFs5W/wIjlBqFD5jQdb8PVjvPX2mnoEJWnXaVx0udYFeLa9wbAKZ2cYcCi/pP4/x1OwPetJ8Dg1OXnEa+uLPttvE6XTw/hyCklgcc4pJ/dphY7IQguGZnigtONVuV3AM0NecpH+oP31Xy8jzeuqdzwkRrSAD3Aq9g84Fp5NVG4ooBv0koaN18KUOuR2itBoBdmuA==" Feb 12 14:41:06 volumio volumio-remote-updater[643]: [2026-02-12 14:41:06] [connect] Successful connection Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: connect: connection refused" Feb 12 14:41:06 volumio volumio[12339]: info: MYVOLUMIO Environment detected Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 12 14:41:06 volumio volumio[12339]: info: Plugin folders cleanup Feb 12 14:41:06 volumio volumio[12339]: info: Scanning into folder /volumio/app/plugins/ Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category audio_interface Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category miscellanea Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category music_service Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category plugins.json Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category system_controller Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category user_interface Feb 12 14:41:06 volumio volumio[12339]: info: Scanning into folder /data/plugins/ Feb 12 14:41:06 volumio volumio[12339]: info: Scanning category music_service Feb 12 14:41:06 volumio volumio[12339]: info: Plugin folders cleanup completed Feb 12 14:41:06 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:06 volumio volumio[12339]: info: ----- Core plugins startup ---- Feb 12 14:41:06 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:06 volumio volumio[12339]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 14:41:06 volumio volumio[12339]: info: Adding plugin upnp to MyMusic Plugins Feb 12 14:41:06 volumio volumio[12339]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 14:41:06 volumio go-librespot[12361]: time="2026-02-12T14:41:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:06 volumio volumio[12339]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 14:41:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:06 volumio volumio[12339]: info: Loading plugins from folder /data/plugins/ Feb 12 14:41:06 volumio volumio[12339]: info: Loading plugin "system"... Feb 12 14:41:06 volumio volumio[12339]: info: Loading plugin "appearance"... Feb 12 14:41:08 volumio volumio[12339]: info: Loading plugin "network"... Feb 12 14:41:08 volumio volumio[12339]: info: Refreshing Cached IP Addresses Feb 12 14:41:08 volumio sudo[12376]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 14:41:08 volumio sudo[12376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:08 volumio sudo[12378]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 14:41:08 volumio sudo[12378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:08 volumio volumio[12339]: info: Loading plugin "services"... Feb 12 14:41:08 volumio sudo[12376]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:08 volumio volumio[12339]: info: Loading plugin "alsa_controller"... Feb 12 14:41:08 volumio sudo[12378]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:08 volumio sudo[12385]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 14:41:08 volumio sudo[12385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:09 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "wizard"... Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "networkfs"... Feb 12 14:41:09 volumio volumio[12339]: info: Starting Udev Watcher for removable devices Feb 12 14:41:09 volumio volumio[12339]: info: Ignoring mount for partition: boot Feb 12 14:41:09 volumio volumio[12339]: info: Ignoring mount for partition: volumio Feb 12 14:41:09 volumio volumio[12339]: info: Ignoring mount for partition: volumio_data Feb 12 14:41:09 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "volumio_command_line_client"... Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "upnp"... Feb 12 14:41:09 volumio volumio[12339]: info: [1770882069218] Starting Upmpd Daemon Feb 12 14:41:09 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "my_music"... Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "mpd"... Feb 12 14:41:09 volumio volumio[12339]: info: Loading plugin "upnp_browser"... Feb 12 14:41:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 12 14:41:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:10 volumio go-librespot[12409]: go-librespot daemon starting... Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=debug msg="app state loaded" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=info msg="zeroconf server listening on port 46779" Feb 12 14:41:10 volumio go-librespot[12410]: time="2026-02-12T14:41:10+07:00" level=debug msg="obtained new client token: AAAenS9FbiVl9fFV/vF+lzTo+GFC0gI7bdUT63G27/vFqu8A4KYX4mB3tH513L63f3pZXIVXe/0KwzodfSqZbp4UK6eWKe7UtqugZEBQAQnYae6ZK/CafPnmWALnTkq+GW7xEmnrzRmbH83n7gm7gf+wCrww0q0U1WKDl60aitgZrKn0+bnAz2trngV1RsVk2oPHtqlkWfVXq/llk21mp+J3lVJX4q1O+O56RI64gHRmAbKoE+6UEo/Fyg==" Feb 12 14:41:11 volumio go-librespot[12410]: time="2026-02-12T14:41:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:11 volumio go-librespot[12410]: time="2026-02-12T14:41:11+07:00" level=debug msg="completed keyexchange" Feb 12 14:41:11 volumio go-librespot[12410]: time="2026-02-12T14:41:11+07:00" level=debug msg="completed challenge" Feb 12 14:41:11 volumio go-librespot[12410]: time="2026-02-12T14:41:11+07: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 12 14:41:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:11 volumio sudo[12385]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:12 volumio volumio[12339]: info: Starting UPNP Browser Feb 12 14:41:12 volumio volumio[12339]: info: Loading plugin "alarm-clock"... Feb 12 14:41:13 volumio volumio[12339]: info: Loading plugin "airplay_emulation"... Feb 12 14:41:13 volumio volumio[12339]: info: Starting Shairport Sync Feb 12 14:41:13 volumio volumio[12339]: info: Loading plugin "last_100"... Feb 12 14:41:13 volumio volumio[12339]: info: Loading plugin "webradio"... Feb 12 14:41:13 volumio volumio[12339]: info: Loading plugin "i2s_dacs"... Feb 12 14:41:13 volumio volumio[12339]: info: Loading plugin "volumiodiscovery"... Feb 12 14:41:13 volumio volumio[12339]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:13 volumio volumio[12339]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:13 volumio volumio[12339]: *** WARNING *** For more information see Feb 12 14:41:13 volumio volumio[12339]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:13 volumio volumio[12339]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:13 volumio volumio[12339]: *** WARNING *** For more information see Feb 12 14:41:13 volumio node[12339]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:13 volumio node[12339]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:13 volumio node[12339]: *** WARNING *** For more information see Feb 12 14:41:13 volumio node[12339]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:13 volumio node[12339]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:13 volumio node[12339]: *** WARNING *** For more information see Feb 12 14:41:13 volumio volumio[12339]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 14:41:13 volumio volumio[12339]: info: Discovery: Started advertising with name: Volumio Feb 12 14:41:13 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 14:41:13 volumio volumio[12339]: info: Loading plugin "spop"... Feb 12 14:41:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 12 14:41:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:14 volumio go-librespot[12422]: go-librespot daemon starting... Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=debug msg="app state loaded" Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:14 volumio go-librespot[12423]: time="2026-02-12T14:41:14+07:00" level=info msg="zeroconf server listening on port 42697" Feb 12 14:41:15 volumio go-librespot[12423]: time="2026-02-12T14:41:15+07:00" level=debug msg="obtained new client token: AAB+byVHAHq6al5ZR7SCsCIpFgzvPnsFQqz4k2e81bSGAxYBhPRkJZ6chah//DiuJDWDTM7l4tgL6fUKVBWpGOSl27ppfGzSDqFwghKq9MoNhlqA+mZx76fTKRMgI0u8hwHvDXEqRPV/PNvYrHAFQJN1X8k7DvkPrnpL0HNxt305Zt9/do6wx3NSGHk2/8GMh0iwJehIIo+nt89TuiSjiGsUztoGQzx1yqSWTBg+R0Td4vgC6RjfQI8=" Feb 12 14:41:15 volumio go-librespot[12423]: time="2026-02-12T14:41:15+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 12 14:41:15 volumio go-librespot[12423]: time="2026-02-12T14:41:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 12 14:41:15 volumio go-librespot[12423]: time="2026-02-12T14:41:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:15 volumio volumio[12339]: info: Loading plugin "ytcr"... Feb 12 14:41:18 volumio volumio[12339]: info: Loading plugin "ytmusic"... Feb 12 14:41:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 12 14:41:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:18 volumio go-librespot[12430]: go-librespot daemon starting... Feb 12 14:41:18 volumio go-librespot[12431]: time="2026-02-12T14:41:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:18 volumio go-librespot[12431]: time="2026-02-12T14:41:18+07:00" level=debug msg="app state loaded" Feb 12 14:41:18 volumio go-librespot[12431]: time="2026-02-12T14:41:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:18 volumio volumio-remote-updater[643]: [2026-02-12 14:41:18] [connect] Successful connection Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "outputs"... Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "albumart"... Feb 12 14:41:19 volumio volumio[12339]: info: Plugin example_plugin is not enabled Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "inputs"... Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "updater_comm"... Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=info msg="zeroconf server listening on port 35545" Feb 12 14:41:19 volumio volumio[12339]: info: Plugin mpdemulation is not enabled Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "rest_api"... Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=debug msg="obtained new client token: AACZgsnzADmgb/gDeZ9FQllDOB9fmKgZQYDUjW+x0bd+ybNncwrRjU31R4ZPMmhyjuYvVF0CHdCQvh3RD1XYv8rW2hRWFg/ZZyhV52Qz1QMNM0hs71qDEhsOUEtwd/rbQqtntUbv01PeXAdcisPS7n9AqMqsorluADcewWi+fu3qrpVlKJlurI9yTd2vuk/pB4FFtqLAY6tKGsY2xwJWEbw9ZLSx7j9Wsxs/1DIRiT89LZnTjrjA/QhYyw==" Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "websocket"... Feb 12 14:41:19 volumio volumio[12339]: info: Starting Socket.io Server version 1.7.4 Feb 12 14:41:19 volumio volumio[12339]: info: Loading plugin "RoonBridge"... Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:19 volumio go-librespot[12431]: time="2026-02-12T14:41:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:20 volumio volumio[12339]: info: Applying required configuration parameters for plugin RoonBridge Feb 12 14:41:20 volumio volumio[12339]: info: Loading i18n strings for locale en Feb 12 14:41:20 volumio volumio[12339]: Updating browse sources language Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:41:20 volumio volumio[12452]: Forking 3 albumart workers Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::initPlayerControls Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 14:41:20 volumio volumio[12339]: Express server listening on port 3000 Feb 12 14:41:20 volumio volumio[12339]: [Metrics] WebUI: 16s 177.53ms Feb 12 14:41:20 volumio volumio[12339]: info: CoreStateMachine::resetVolumioState Feb 12 14:41:20 volumio volumio[12339]: info: CoreStateMachine::getcurrentVolume Feb 12 14:41:20 volumio volumio[12339]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 14:41:20 volumio sudo[12497]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 14:41:20 volumio sudo[12497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:20 volumio sudo[12499]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 14:41:20 volumio sudo[12499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:20 volumio sudo[12497]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:20 volumio volumio[12339]: info: Volumio Network Manager: Network status updated: 1 Feb 12 14:41:20 volumio sudo[12499]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:21 volumio volumio[12339]: info: VolumeController:: Volume=100 Mute =false Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::pushState Feb 12 14:41:21 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::updateTrackBlock Feb 12 14:41:21 volumio volumio[12339]: info: CorePlayQueue::getTrackBlock Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 14:41:21 volumio volumio-remote-updater[643]: [2026-02-12 14:41:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770882078 101 Feb 12 14:41:21 volumio volumio[12339]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:21 volumio volumio[12339]: info: Reloading queue from file Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::setRepeat null single undefined Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::pushState Feb 12 14:41:21 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::setRandom null Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::pushState Feb 12 14:41:21 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Feb 12 14:41:21 volumio volumio[12339]: info: Setting Device type: Raspberry PI Feb 12 14:41:21 volumio volumio[12339]: info: Completed loading Core Plugins Feb 12 14:41:21 volumio volumio[12339]: info: Preparing to generate the ALSA configuration file Feb 12 14:41:21 volumio volumio[12339]: info: VolumeController:: Volume=100 Mute =false Feb 12 14:41:21 volumio volumio[12339]: info: CoreStateMachine::pushState Feb 12 14:41:21 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Feb 12 14:41:21 volumio volumio[12339]: info: Asound.conf file unchanged, so no further update is needed Feb 12 14:41:21 volumio volumio[12339]: info: Output device has changed, restarting MPD Feb 12 14:41:21 volumio volumio[12339]: info: Output device has changed, restarting Shairport Sync Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:21 volumio sudo[12513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 14:41:21 volumio sudo[12513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:21 volumio sudo[12515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 14:41:21 volumio sudo[12515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:21 volumio sudo[12515]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:21 volumio sudo[12517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 14:41:21 volumio sudo[12517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:21 volumio volumio[12339]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 14:41:21 volumio volumio[12339]: info: ___________ START PLUGINS ___________ Feb 12 14:41:21 volumio volumio[12339]: info: ControllerMpd::onStart: Initializing MPD Feb 12 14:41:21 volumio volumio[12339]: info: Creating MPD Configuration file Feb 12 14:41:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 14:41:21 volumio sudo[12513]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:41:21 volumio volumio[12339]: info: [1770882081885] CoreMusicLibrary::Adding element Media Servers Feb 12 14:41:21 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:41:21 volumio sudo[12526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 14:41:21 volumio sudo[12528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 14:41:21 volumio sudo[12526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:21 volumio sudo[12528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:21 volumio sudo[12526]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:21 volumio volumio[12339]: info: UPNP Browser: Client initialized successfully Feb 12 14:41:22 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 14:41:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 14:41:22 volumio systemd[1]: mpd.service: Consumed 7.029s CPU time. Feb 12 14:41:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 14:41:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 14:41:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:22 volumio volumio[12339]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 14:41:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 14:41:22 volumio volumio[12339]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:41:22 volumio volumio[12339]: info: [1770882082178] CoreMusicLibrary::Adding element Last_100 Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:41:22 volumio volumio[12339]: info: [1770882082202] CoreMusicLibrary::Adding element Webradio Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:41:22 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 14:41:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 14:41:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 14:41:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 14:41:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 14:41:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 14:41:22 volumio volumio[12339]: info: Initializing BBC Radios Feb 12 14:41:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:22 volumio volumio[12339]: info: Creating Spotify config file Feb 12 14:41:22 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:22 volumio sudo[12543]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 14:41:22 volumio sudo[12543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 14:41:22 volumio sudo[12543]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Feb 12 14:41:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:22 volumio go-librespot[12559]: go-librespot daemon starting... Feb 12 14:41:23 volumio go-librespot[12560]: time="2026-02-12T14:41:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:23 volumio volumio[12462]: Starting albumart workers Feb 12 14:41:23 volumio volumio[12463]: Starting albumart workers Feb 12 14:41:23 volumio go-librespot[12560]: time="2026-02-12T14:41:23+07:00" level=info msg="zeroconf server listening on port 34349" Feb 12 14:41:23 volumio volumio[12339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:41:23 volumio volumio[12339]: info: [1770882083855] CoreMusicLibrary::Adding element YouTube Music Feb 12 14:41:23 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:41:23 volumio volumio[12339]: Cannot find translation for source YouTube Music Feb 12 14:41:23 volumio volumio[12339]: info: Volumio Calling Home Feb 12 14:41:23 volumio sudo[12569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 12 14:41:23 volumio sudo[12569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:24 volumio volumio[12464]: Starting albumart workers Feb 12 14:41:24 volumio sudo[12569]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:24 volumio volumio[12339]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 14:41:24 volumio volumio[12339]: info: Discovery: Found device Volumio Feb 12 14:41:24 volumio volumio[12339]: info: CoreCommandRouter::volumioGetState Feb 12 14:41:24 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:24 volumio volumio[12339]: info: MPD Permissions set Feb 12 14:41:24 volumio volumio[12339]: info: MPD Permissions set Feb 12 14:41:24 volumio volumio[12339]: info: Upmpdcli Daemon Started Feb 12 14:41:24 volumio volumio[12339]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 14:41:24 volumio volumio[12339]: info: Discovery: Found device Volumio Feb 12 14:41:24 volumio volumio[12339]: info: CoreCommandRouter::volumioGetState Feb 12 14:41:24 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:24 volumio volumio[12339]: info: Spotify config file written Feb 12 14:41:24 volumio sudo[12575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 14:41:24 volumio sudo[12575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:24 volumio volumio[12339]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 12 14:41:25 volumio volumio[12339]: info: Volumio called home Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 14:41:25 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 14:41:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:41:25 volumio volumio[12339]: info: No need to fix Spotify hosts Feb 12 14:41:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:25 volumio go-librespot[12582]: go-librespot daemon starting... Feb 12 14:41:25 volumio sudo[12575]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:25 volumio go-librespot[12588]: time="2026-02-12T14:41:25+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:25 volumio go-librespot[12588]: time="2026-02-12T14:41:25+07:00" level=debug msg="app state loaded" Feb 12 14:41:25 volumio go-librespot[12588]: time="2026-02-12T14:41:25+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=info msg="zeroconf server listening on port 38601" Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=debug msg="obtained new client token: AAAi4e5gauBFh0mn5SC4kR7oLdCe0PZiKp8yz0EXFMYE7+RC2vbu4JMAeVKAjx5j1l6us6BkRAuM4XAyzRS0fu5gRBDfCzkPIFE7ZNXJIHOcGe49NeHPDUN7Rs+mEp+U9rLa2D+pDur2/dsczblUBL/v5TvSHFiZqTcJr+1a+4oxQ+d+WFuQsWsDd5jLxvihx19F6o+BME3WXaJo8xICCHT4EhzwUgZijEFtRDOnQvQdC1ps7rY28Ko8mw==" Feb 12 14:41:26 volumio volumio[12339]: info: Starting Shairport Sync Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:26 volumio volumio[12339]: info: Starting Shairport Sync Feb 12 14:41:26 volumio volumio[12339]: info: Starting Shairport Sync Feb 12 14:41:26 volumio sudo[12615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 14:41:26 volumio sudo[12615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:26 volumio go-librespot[12588]: time="2026-02-12T14:41:26+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:26 volumio sudo[12617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 14:41:26 volumio sudo[12617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:26 volumio sudo[12619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 14:41:26 volumio sudo[12619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 14:41:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 14:41:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 14:41:26 volumio systemd[1]: shairport-sync.service: Consumed 2.276s CPU time. Feb 12 14:41:26 volumio volumio[12339]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 14:41:26 volumio volumio[12339]: SPOTIFY: BQCNYChO9EO36P-MoF363CY8pAneWSH1YPTbCQr_o1LYrUQ_7ZQEmz_KKLjrDJyYc2SnhAALZr58aw2N3qyIFoSwGRS2WFobC52B9ziKajMnz4hPr79hLI6cSIZZgz3_B-Cv4rzAQNTmfmD3cq1TB_AlRNcKg91afkUU41TvlVgZ1IN_vfNG6T8itqxniOcD_sC6qvk3tXEDWhFsKWdmOICtGPVImvfXKbmo0UCTFxASBzlL83IvWxwTtFpxcEK2aVFKljUTVu3kevgzmhJTVnsu9FuFKVv2CMiARlgHuNHzH5kQd6BLN62z Feb 12 14:41:26 volumio volumio[12339]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 14:41:26 volumio volumio[12339]: info: New Spotify access token = BQCNYChO9EO36P-MoF363CY8pAneWSH1YPTbCQr_o1LYrUQ_7ZQEmz_KKLjrDJyYc2SnhAALZr58aw2N3qyIFoSwGRS2WFobC52B9ziKajMnz4hPr79hLI6cSIZZgz3_B-Cv4rzAQNTmfmD3cq1TB_AlRNcKg91afkUU41TvlVgZ1IN_vfNG6T8itqxniOcD_sC6qvk3tXEDWhFsKWdmOICtGPVImvfXKbmo0UCTFxASBzlL83IvWxwTtFpxcEK2aVFKljUTVu3kevgzmhJTVnsu9FuFKVv2CMiARlgHuNHzH5kQd6BLN62z Feb 12 14:41:26 volumio volumio[12339]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 14:41:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 14:41:27 volumio sudo[12617]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:27 volumio sudo[12615]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:27 volumio sudo[12619]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:27 volumio volumio[12339]: info: CoreCommandRouter::volumioGetState Feb 12 14:41:27 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:27 volumio volumio[12339]: info: Shairport-Sync Started Feb 12 14:41:27 volumio volumio[12339]: Error adding Membership: Error: addMembership EINVAL Feb 12 14:41:27 volumio volumio[12339]: info: Shairport-Sync Started Feb 12 14:41:27 volumio volumio[12339]: info: Shairport-Sync Started Feb 12 14:41:27 volumio volumio[12339]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 12 14:41:27 volumio volumio[12339]: info: Spotify Successfully logged in Feb 12 14:41:27 volumio volumio[12339]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:41:27 volumio volumio[12339]: info: [1770882087656] CoreMusicLibrary::Adding element Spotify Feb 12 14:41:27 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:41:27 volumio volumio[12339]: Cannot find translation for source YouTube Music Feb 12 14:41:27 volumio volumio[12339]: Cannot find translation for source Spotify Feb 12 14:41:27 volumio volumio[12339]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 12 14:41:27 volumio volumio[12339]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 14:41:28 volumio volumio[12339]: info: VolumeController:: Volume=100 Mute =false Feb 12 14:41:28 volumio volumio[12339]: info: CoreCommandRouter::volumioGetState Feb 12 14:41:28 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:28 volumio volumio[12339]: info: CoreStateMachine::pushState Feb 12 14:41:28 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:28 volumio volumio[12339]: info: CoreCommandRouter::volumioPushState Feb 12 14:41:29 volumio volumio[12339]: info: go-librespot daemon successfully initialized Feb 12 14:41:29 volumio mpd[12558]: 2026-02-12T14:41:29 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 14:41:29 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 14:41:29 volumio sudo[12528]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:29 volumio sudo[12517]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 12 14:41:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:29 volumio volumio[12339]: error: MPD error: The expression evaluated to a falsy value: Feb 12 14:41:29 volumio volumio[12339]: assert.ok(self.idling) Feb 12 14:41:29 volumio volumio[12339]: error: The expression evaluated to a falsy value: Feb 12 14:41:29 volumio volumio[12339]: assert.ok(self.idling) Feb 12 14:41:29 volumio volumio[12339]: info: MPD running with PID12558 Feb 12 14:41:29 volumio volumio[12339]: ,establishing connection Feb 12 14:41:29 volumio volumio[12339]: error: updateQueue error: null Feb 12 14:41:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:29 volumio go-librespot[12659]: go-librespot daemon starting... Feb 12 14:41:29 volumio go-librespot[12660]: time="2026-02-12T14:41:29+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:29 volumio go-librespot[12660]: time="2026-02-12T14:41:29+07:00" level=debug msg="app state loaded" Feb 12 14:41:29 volumio go-librespot[12660]: time="2026-02-12T14:41:29+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:29 volumio volumio[12339]: info: Completed starting Core Plugins Feb 12 14:41:29 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:29 volumio volumio[12339]: info: ----- MyVolumio plugins startup ---- Feb 12 14:41:29 volumio volumio[12339]: info: ------------------------------------------- Feb 12 14:41:29 volumio volumio[12339]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 14:41:29 volumio volumio[12339]: error: updateQueue error: null Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=info msg="zeroconf server listening on port 40669" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="obtained new client token: AABmsU5kNLURRvV+9BitNYepEVpL84tVkcxrYb4qUDL9XQa6jQx3Dds11B5aObcxqn8rzRP/c7SVadX4pBhGmUKkcJbhk0M8IHHuKuIf547lcPN5KXITbkYKyN/eAxBeDjxDiLnEjOGPZE6D0nX9Dd8vdnZWtXLe2pLPz8XrSTMnq70E8eZ49iNY0EFr/tpy8yMT5Fcoi+p7g+wLd5mftF/DnBTU6NOmx42yuQRHAN4viDu5Sfu7BJN8Tg==" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="completed keyexchange" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07:00" level=debug msg="completed challenge" Feb 12 14:41:30 volumio go-librespot[12660]: time="2026-02-12T14:41:30+07: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 12 14:41:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:32 volumio volumio[12339]: info: Initializing connection to go-librespot Websocket Feb 12 14:41:32 volumio volumio[12339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 14:41:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 12 14:41:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:34 volumio go-librespot[12668]: go-librespot daemon starting... Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=debug msg="app state loaded" Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=info msg="zeroconf server listening on port 37583" Feb 12 14:41:34 volumio volumio[12339]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 12 14:41:34 volumio go-librespot[12669]: time="2026-02-12T14:41:34+07:00" level=debug msg="obtained new client token: AAARgioLwKZXk/UUuKu+zYJ/xj6Z3pAdXr8nCdZgPMVslwc3R+sWbBsIdr4lKAHnAH9U7x2cvTdDAoR2BjM4xZAlxXndm0avqRQhfizDYj6j3CZVimdD0mmZ6kICMUb/CuLoDDNix89AsOLkdax1XQiZiZP8F/fjQjmiUgtHlDucos4BLfiJxettbePbsG/kjus3FmmDFHB1iszw/Fa+ULjsTSTLIjC91BJ63VU9BjidJNToEJDqt4ZQaw==" Feb 12 14:41:35 volumio go-librespot[12669]: time="2026-02-12T14:41:35+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:35 volumio go-librespot[12669]: time="2026-02-12T14:41:35+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:35 volumio volumio[12339]: info: Initializing connection to go-librespot Websocket Feb 12 14:41:35 volumio volumio[12339]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 14:41:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 12 14:41:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:38 volumio go-librespot[12677]: go-librespot daemon starting... Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=debug msg="app state loaded" Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:38 volumio volumio[12339]: info: Initializing connection to go-librespot Websocket Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=debug msg="new websocket client" Feb 12 14:41:38 volumio volumio[12339]: info: Connection to go-librespot Websocket established Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin bluetooth to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin multiroom to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin metavolumio to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin cd_controller to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 12 14:41:38 volumio volumio[12339]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:38 volumio go-librespot[12678]: time="2026-02-12T14:41:38+07:00" level=info msg="zeroconf server listening on port 39861" Feb 12 14:41:39 volumio go-librespot[12678]: time="2026-02-12T14:41:39+07:00" level=debug msg="obtained new client token: AACMTIoTyUD2fUpCweoinmD8Xe+1I7ufxrUSwGIpQtuhXgx8YblXgEgE9u29R2dBJCuDXG7IApQBM1MXHdxvSQbPQQFO4OxjWmTUS0cgKAsqIpBarE5rS8aP/cDrbHsEs3YVGlrjwHEozMtzg3IidSWp5+coPKuFkFmXlZHH87Q2UENTy/C25HxXKFBCXD9QWMNlUgGYW4nWAWonPWe/5IkwXGA9Ns2ler2D7OKgH5tcPnKRUVraq+Q=" Feb 12 14:41:39 volumio go-librespot[12678]: time="2026-02-12T14:41:39+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:39 volumio go-librespot[12678]: time="2026-02-12T14:41:39+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:40 volumio volumio[12339]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 14:41:40 volumio volumio[12339]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 14:41:40 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:40 volumio volumio[12339]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:41:40 volumio volumio[12339]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 14:41:40 volumio volumio[12339]: info: MyVolumio login type: Token Feb 12 14:41:40 volumio volumio[12339]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 14:41:40 volumio volumio[12339]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 14:41:42 volumio volumio[12339]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 14:41:42 volumio volumio[12339]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 14:41:42 volumio volumio[12339]: info: Streaming services startup Feb 12 14:41:42 volumio volumio[12339]: info: Starting Streaming Daemon Feb 12 14:41:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 12 14:41:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:42 volumio sudo[12701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 14:41:42 volumio sudo[12701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:42 volumio volumio[12339]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 14:41:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:42 volumio go-librespot[12705]: go-librespot daemon starting... Feb 12 14:41:42 volumio sudo[12701]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:42 volumio go-librespot[12708]: time="2026-02-12T14:41:42+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:42 volumio go-librespot[12708]: time="2026-02-12T14:41:42+07:00" level=debug msg="app state loaded" Feb 12 14:41:42 volumio go-librespot[12708]: time="2026-02-12T14:41:42+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:42 volumio volumio[12339]: info: Getting Spotify volume Feb 12 14:41:42 volumio volumio[12339]: info: Connection to go-librespot Websocket closed Feb 12 14:41:42 volumio volumio[12339]: error: Cannot start Volumio Streaming Daemon Feb 12 14:41:42 volumio volumio[12339]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 14:41:42 volumio volumio[12339]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 14:41:42 volumio volumio[12339]: 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 12 14:41:43 volumio volumio[12339]: info: CoreCommandRouter::volumioGetState Feb 12 14:41:43 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:43 volumio volumio[12339]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 12 14:41:43 volumio volumio[12339]: SPOTIFY: SPOTIFY VOLUME undefined Feb 12 14:41:43 volumio volumio[12339]: SPOTIFY: VOLUMIO VOLUME 100 Feb 12 14:41:43 volumio volumio[12339]: info: Aligning Spotify Volume to Volumio Volume Feb 12 14:41:43 volumio volumio[12339]: info: CoreCommandRouter::volumioGetState Feb 12 14:41:43 volumio volumio[12339]: info: CorePlayQueue::getTrack 0 Feb 12 14:41:43 volumio volumio[12339]: info: Setting Spotify Volume from Volumio: 100 Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=info msg="zeroconf server listening on port 46177" Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=debug msg="obtained new client token: AABuCdNHqG/6yrUEE0qcgkQpySy2nOzvpIjmPgpFb5CzSPb9fIDhzL598tQkOvvjG9sedtQjRcccT/aV8HLN5l2yyTlb/P+QZvr8Fwvec5eB2bYJXs36FiaqP6gvWK4all8UvyB970QUVvZkB3CPtCFRVEL/unknWscQ9/Bec6/+gE31okKHuAH5oL+5xiIhMc0gJvTI5WSY7XrUUH5ZGGDWdL7w7je6/ncYpwl5/cu7wS/S/lbisrBQZA==" Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:43 volumio volumio[12339]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 12 14:41:43 volumio go-librespot[12708]: time="2026-02-12T14:41:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:43 volumio volumio[12339]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:41:43 volumio volumio[12339]: Error: socket hang up Feb 12 14:41:43 volumio volumio[12339]: at connResetException (node:internal/errors:720:14) Feb 12 14:41:43 volumio volumio[12339]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 12 14:41:43 volumio volumio[12339]: at Socket.emit (node:events:526:35) Feb 12 14:41:43 volumio volumio[12339]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 12 14:41:43 volumio volumio[12339]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 12 14:41:43 volumio volumio[12339]: code: 'ECONNRESET', Feb 12 14:41:43 volumio volumio[12339]: response: undefined Feb 12 14:41:43 volumio volumio[12339]: } Feb 12 14:41:43 volumio volumio[12339]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:41:45 volumio sudo[12730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 14:40' Feb 12 14:41:45 volumio sudo[12730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:45 volumio sudo[12730]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:45 volumio volumio-remote-updater[643]: [2026-02-12 14:41:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 12 14:41:45 volumio volumio-remote-updater[643]: [2026-02-12 14:41:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 12 14:41:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 14:41:45 volumio systemd[1]: volumio.service: Consumed 55.686s CPU time. Feb 12 14:41:45 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 14:41:45 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 14:41:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13312. Feb 12 14:41:46 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 14:41:46 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 14:41:46 volumio systemd[1]: volumio.service: Consumed 55.686s CPU time. Feb 12 14:41:46 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 14:41:46 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 14:41:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 12 14:41:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:47 volumio go-librespot[12759]: go-librespot daemon starting... Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=debug msg="app state loaded" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=info msg="zeroconf server listening on port 32779" Feb 12 14:41:47 volumio go-librespot[12760]: time="2026-02-12T14:41:47+07:00" level=debug msg="obtained new client token: AAAR8HbPH++6JwlycSf2IooEQ9jG/7EVv9x6GMeaJiTRjkQjY0MUzuTlS+Nfpl74+24aWLp7EjtFvKqAvyeLSmlZpJXbaw9A61nNPataF+LAC5S60iSqpkahU0HRLQlbp41JwKD8dUbhnD8WQrDUBJkYQ/o5eYPrcZ5NEGgwX2XIVjeQEg/R3eUWGAoVQMC0c8TA4aA51VdW9S+eugQGfIMekATAqI70c4itpIfGQw77+Ys8yAXj+R7okg==" Feb 12 14:41:48 volumio go-librespot[12760]: time="2026-02-12T14:41:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:48 volumio go-librespot[12760]: time="2026-02-12T14:41:48+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:49 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:41:49 volumio volumio[12744]: info: ----- Volumio3 ---- Feb 12 14:41:49 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:41:49 volumio volumio[12744]: info: ----- System startup ---- Feb 12 14:41:49 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:41:50 volumio volumio-remote-updater[643]: [2026-02-12 14:41:50] [connect] Successful connection Feb 12 14:41:51 volumio volumio[12744]: info: MYVOLUMIO Environment detected Feb 12 14:41:51 volumio volumio[12744]: info: Plugin folders cleanup Feb 12 14:41:51 volumio volumio[12744]: info: Scanning into folder /volumio/app/plugins/ Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category audio_interface Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category miscellanea Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category music_service Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category plugins.json Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category system_controller Feb 12 14:41:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category user_interface Feb 12 14:41:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:51 volumio volumio[12744]: info: Scanning into folder /data/plugins/ Feb 12 14:41:51 volumio volumio[12744]: info: Scanning category music_service Feb 12 14:41:51 volumio volumio[12744]: info: Plugin folders cleanup completed Feb 12 14:41:51 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:41:51 volumio volumio[12744]: info: ----- Core plugins startup ---- Feb 12 14:41:51 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:41:51 volumio volumio[12744]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 14:41:51 volumio volumio[12744]: info: Adding plugin upnp to MyMusic Plugins Feb 12 14:41:51 volumio volumio[12744]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 14:41:51 volumio volumio[12744]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 14:41:51 volumio volumio[12744]: info: Loading plugins from folder /data/plugins/ Feb 12 14:41:51 volumio volumio[12744]: info: Loading plugin "system"... Feb 12 14:41:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:51 volumio go-librespot[12776]: go-librespot daemon starting... Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=debug msg="app state loaded" Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:51 volumio volumio[12744]: info: Loading plugin "appearance"... Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:51 volumio go-librespot[12777]: time="2026-02-12T14:41:51+07:00" level=info msg="zeroconf server listening on port 42971" Feb 12 14:41:52 volumio go-librespot[12777]: time="2026-02-12T14:41:52+07:00" level=debug msg="obtained new client token: AADwkcK7+PdHsaHC4ObAg3V9WeGZW0Rm7m3G6hOJrIYODB+bkgZFtxstP5MKQuXSDALzEWULIW4WRy0zcANomarq8V3jUKXiNxoJP4+yEBKzjDugsPeljCWmFEDuIw5bqlkrv9UzYvwEPNQNCVLX5VXK9aGnj3jAMwq8N4Asiskm4UugX5xNrK/h0a/JTYoGeQlWSNXWlTBCygpsCOVWOd+TUV7Z/4gC995hdEXG0JK1xfR+2KDazdk=" Feb 12 14:41:52 volumio go-librespot[12777]: time="2026-02-12T14:41:52+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:52 volumio go-librespot[12777]: time="2026-02-12T14:41:52+07:00" level=debug msg="completed keyexchange" Feb 12 14:41:52 volumio go-librespot[12777]: time="2026-02-12T14:41:52+07:00" level=debug msg="completed challenge" Feb 12 14:41:52 volumio go-librespot[12777]: time="2026-02-12T14:41:52+07: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 12 14:41:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "network"... Feb 12 14:41:53 volumio volumio[12744]: info: Refreshing Cached IP Addresses Feb 12 14:41:53 volumio sudo[12788]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 14:41:53 volumio sudo[12788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:53 volumio sudo[12790]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 14:41:53 volumio sudo[12790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "services"... Feb 12 14:41:53 volumio sudo[12788]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "alsa_controller"... Feb 12 14:41:53 volumio sudo[12790]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:53 volumio sudo[12797]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 14:41:53 volumio sudo[12797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:41:53 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "wizard"... Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "networkfs"... Feb 12 14:41:53 volumio volumio[12744]: info: Starting Udev Watcher for removable devices Feb 12 14:41:53 volumio volumio[12744]: info: Ignoring mount for partition: boot Feb 12 14:41:53 volumio volumio[12744]: info: Ignoring mount for partition: volumio Feb 12 14:41:53 volumio volumio[12744]: info: Ignoring mount for partition: volumio_data Feb 12 14:41:53 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "volumio_command_line_client"... Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "upnp"... Feb 12 14:41:53 volumio volumio[12744]: info: [1770882113770] Starting Upmpd Daemon Feb 12 14:41:53 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "my_music"... Feb 12 14:41:53 volumio volumio[12744]: info: Loading plugin "mpd"... Feb 12 14:41:54 volumio volumio[12744]: info: Loading plugin "upnp_browser"... Feb 12 14:41:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 12 14:41:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:55 volumio go-librespot[12821]: go-librespot daemon starting... Feb 12 14:41:55 volumio go-librespot[12822]: time="2026-02-12T14:41:55+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:55 volumio go-librespot[12822]: time="2026-02-12T14:41:55+07:00" level=debug msg="app state loaded" Feb 12 14:41:55 volumio go-librespot[12822]: time="2026-02-12T14:41:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=info msg="zeroconf server listening on port 42235" Feb 12 14:41:56 volumio sudo[12797]: pam_unix(sudo:session): session closed for user root Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=debug msg="obtained new client token: AABKUf9NLqTfnLddcTjQVVRt6NtpexBTruaKtJGbWBThIuW/JjicpLEfyV0XO1MuFFVWYMAknQCBuHp6h0cgWSMQLlL09wZH6ljonrJwGboFbRPtZSQSLNGgNms1xRGxJWantAqC6bbvrbc2BgVxsQuRhz9cDjMndX+NVbogNGtFvFFs3UO0rzYotKcT2pVWrKH9FCl0RTGK22RvzTZcZU02hweeEp4wR+x5Ak2k6B3S8O5jVdoV/HUOsA==" Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:41:56 volumio go-librespot[12822]: time="2026-02-12T14:41:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:41:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:41:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:41:57 volumio volumio[12744]: info: Starting UPNP Browser Feb 12 14:41:57 volumio volumio[12744]: info: Loading plugin "alarm-clock"... Feb 12 14:41:57 volumio volumio[12744]: info: Loading plugin "airplay_emulation"... Feb 12 14:41:57 volumio volumio[12744]: info: Starting Shairport Sync Feb 12 14:41:57 volumio volumio[12744]: info: Loading plugin "last_100"... Feb 12 14:41:57 volumio volumio[12744]: info: Loading plugin "webradio"... Feb 12 14:41:57 volumio volumio[12744]: info: Loading plugin "i2s_dacs"... Feb 12 14:41:57 volumio volumio[12744]: info: Loading plugin "volumiodiscovery"... Feb 12 14:41:58 volumio volumio[12744]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:58 volumio volumio[12744]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:58 volumio node[12744]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:58 volumio volumio[12744]: *** WARNING *** For more information see Feb 12 14:41:58 volumio volumio[12744]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:58 volumio volumio[12744]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:58 volumio volumio[12744]: *** WARNING *** For more information see Feb 12 14:41:58 volumio node[12744]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:58 volumio node[12744]: *** WARNING *** For more information see Feb 12 14:41:58 volumio node[12744]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 14:41:58 volumio node[12744]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 14:41:58 volumio node[12744]: *** WARNING *** For more information see Feb 12 14:41:58 volumio volumio[12744]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 14:41:58 volumio volumio[12744]: info: Discovery: Started advertising with name: Volumio Feb 12 14:41:58 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 14:41:58 volumio volumio[12744]: info: Loading plugin "spop"... Feb 12 14:41:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 12 14:41:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:41:59 volumio go-librespot[12832]: go-librespot daemon starting... Feb 12 14:41:59 volumio go-librespot[12833]: time="2026-02-12T14:41:59+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:41:59 volumio volumio[12744]: info: Loading plugin "ytcr"... Feb 12 14:41:59 volumio go-librespot[12833]: time="2026-02-12T14:41:59+07:00" level=debug msg="app state loaded" Feb 12 14:41:59 volumio go-librespot[12833]: time="2026-02-12T14:41:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=info msg="zeroconf server listening on port 46511" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="obtained new client token: AAAvAPyqJIurpnYmwZ8yu2pZXIPwjzcGquRUy+EnftqbXlWgo7EXQVgtdI6rmmdi/1ocn6HczkOsWwAVSsDyBE61CO2fi2wsF46dU6nig0sznTYv2EXOPtv3ZRmTKSZx+2I2K8jmnjFUfmAIrGSeixO86oQaUTltdllDScwKTwVPxLqQkvxO/y8smO44nireXUFcDtcB7u+yrEB89S7jVQd287UL6hStkM9DERskkVwxVfYmHP5LdSBdVg==" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="completed keyexchange" Feb 12 14:42:00 volumio go-librespot[12833]: time="2026-02-12T14:42:00+07:00" level=debug msg="completed challenge" Feb 12 14:42:01 volumio go-librespot[12833]: time="2026-02-12T14:42:01+07: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 12 14:42:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:02 volumio volumio[12744]: info: Loading plugin "ytmusic"... Feb 12 14:42:03 volumio volumio-remote-updater[643]: [2026-02-12 14:42:03] [connect] Successful connection Feb 12 14:42:03 volumio volumio[12744]: info: Loading plugin "outputs"... Feb 12 14:42:03 volumio volumio[12744]: info: Loading plugin "albumart"... Feb 12 14:42:03 volumio volumio[12744]: info: Plugin example_plugin is not enabled Feb 12 14:42:03 volumio volumio[12744]: info: Loading plugin "inputs"... Feb 12 14:42:03 volumio volumio[12744]: info: Loading plugin "updater_comm"... Feb 12 14:42:04 volumio volumio[12744]: info: Plugin mpdemulation is not enabled Feb 12 14:42:04 volumio volumio[12744]: info: Loading plugin "rest_api"... Feb 12 14:42:04 volumio volumio[12744]: info: Loading plugin "websocket"... Feb 12 14:42:04 volumio volumio[12744]: info: Starting Socket.io Server version 1.7.4 Feb 12 14:42:04 volumio volumio[12744]: info: Loading plugin "RoonBridge"... Feb 12 14:42:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 12 14:42:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:04 volumio go-librespot[12867]: go-librespot daemon starting... Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=debug msg="app state loaded" Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:04 volumio volumio[12744]: info: Applying required configuration parameters for plugin RoonBridge Feb 12 14:42:04 volumio volumio[12744]: info: Loading i18n strings for locale en Feb 12 14:42:04 volumio volumio[12744]: Updating browse sources language Feb 12 14:42:04 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:42:04 volumio volumio[12857]: Forking 3 albumart workers Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 14:42:04 volumio go-librespot[12868]: time="2026-02-12T14:42:04+07:00" level=info msg="zeroconf server listening on port 39941" Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::initPlayerControls Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: Express server listening on port 3000 Feb 12 14:42:05 volumio volumio[12744]: [Metrics] WebUI: 16s 270.66ms Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::resetVolumioState Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::getcurrentVolume Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 14:42:05 volumio go-librespot[12868]: time="2026-02-12T14:42:05+07:00" level=debug msg="obtained new client token: AADcq5lCqJ8Pp7AtkKyO8IFsPxa2Jvkf3dKWBrRiT7Dm3CSex83OmYsz7GZLkZUfP/P9N4SYRTZpySeLA8VMMrO3vHNXXpcUwOmMEjM3xXeQpw19+L+hRpzu8p/PkCH5eIT1swc1nW86pvAkgxBwl4Ir75Qj8vxFnWChAmuZbKY9V/IoJ7ldvHXjOSDcZzadpXav6WKJRnhJRXU2MILz+rzfvQpovlPwYuj8d/dBJ8ZSazlcyGBEwRA=" Feb 12 14:42:05 volumio sudo[12912]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 14:42:05 volumio sudo[12912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:05 volumio sudo[12909]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 14:42:05 volumio sudo[12909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:05 volumio sudo[12912]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:05 volumio sudo[12909]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:05 volumio volumio[12744]: info: Volumio Network Manager: Network status updated: 1 Feb 12 14:42:05 volumio go-librespot[12868]: time="2026-02-12T14:42:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:42:05 volumio go-librespot[12868]: time="2026-02-12T14:42:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:42:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:05 volumio volumio[12744]: info: VolumeController:: Volume=100 Mute =false Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::pushState Feb 12 14:42:05 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::volumioPushState Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::updateTrackBlock Feb 12 14:42:05 volumio volumio[12744]: info: CorePlayQueue::getTrackBlock Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 14:42:05 volumio volumio-remote-updater[643]: [2026-02-12 14:42:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770882123 101 Feb 12 14:42:05 volumio volumio[12744]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:05 volumio volumio[12744]: info: Reloading queue from file Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::setRepeat null single undefined Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::pushState Feb 12 14:42:05 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::volumioPushState Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::setRandom null Feb 12 14:42:05 volumio volumio[12744]: info: CoreStateMachine::pushState Feb 12 14:42:05 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:05 volumio volumio[12744]: info: CoreCommandRouter::volumioPushState Feb 12 14:42:05 volumio volumio[12744]: info: Setting Device type: Raspberry PI Feb 12 14:42:05 volumio volumio[12744]: info: Completed loading Core Plugins Feb 12 14:42:05 volumio volumio[12744]: info: Preparing to generate the ALSA configuration file Feb 12 14:42:06 volumio volumio[12744]: info: VolumeController:: Volume=100 Mute =false Feb 12 14:42:06 volumio volumio[12744]: info: CoreStateMachine::pushState Feb 12 14:42:06 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::volumioPushState Feb 12 14:42:06 volumio volumio[12744]: info: Asound.conf file unchanged, so no further update is needed Feb 12 14:42:06 volumio volumio[12744]: info: Output device has changed, restarting MPD Feb 12 14:42:06 volumio sudo[12926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 14:42:06 volumio sudo[12926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:06 volumio volumio[12744]: info: Output device has changed, restarting Shairport Sync Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:06 volumio sudo[12926]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:06 volumio sudo[12931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 14:42:06 volumio sudo[12931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:06 volumio sudo[12929]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 14:42:06 volumio volumio[12744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 14:42:06 volumio sudo[12929]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:06 volumio volumio[12744]: info: ___________ START PLUGINS ___________ Feb 12 14:42:06 volumio sudo[12929]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:06 volumio volumio[12744]: info: ControllerMpd::onStart: Initializing MPD Feb 12 14:42:06 volumio volumio[12744]: info: Creating MPD Configuration file Feb 12 14:42:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:42:06 volumio volumio[12744]: info: [1770882126483] CoreMusicLibrary::Adding element Media Servers Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:42:06 volumio sudo[12939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 14:42:06 volumio sudo[12939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:06 volumio sudo[12939]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:06 volumio volumio[12744]: info: UPNP Browser: Client initialized successfully Feb 12 14:42:06 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 14:42:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 14:42:06 volumio systemd[1]: mpd.service: Consumed 7.099s CPU time. Feb 12 14:42:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 14:42:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 14:42:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 14:42:06 volumio sudo[12941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 14:42:06 volumio sudo[12941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 14:42:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 14:42:06 volumio volumio[12744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:06 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 14:42:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 14:42:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 14:42:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 14:42:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 14:42:06 volumio volumio[12744]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:42:06 volumio volumio[12744]: info: [1770882126812] CoreMusicLibrary::Adding element Last_100 Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:42:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:42:06 volumio volumio[12744]: info: [1770882126841] CoreMusicLibrary::Adding element Webradio Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:42:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 14:42:06 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 14:42:06 volumio volumio[12744]: info: Initializing BBC Radios Feb 12 14:42:07 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 14:42:07 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:07 volumio volumio[12744]: info: Creating Spotify config file Feb 12 14:42:07 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:07 volumio sudo[12955]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 14:42:07 volumio sudo[12955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 14:42:07 volumio sudo[12955]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:07 volumio volumio[12876]: Starting albumart workers Feb 12 14:42:08 volumio volumio[12877]: Starting albumart workers Feb 12 14:42:08 volumio volumio[12744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:42:08 volumio volumio[12744]: info: [1770882128304] CoreMusicLibrary::Adding element YouTube Music Feb 12 14:42:08 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:42:08 volumio volumio[12744]: Cannot find translation for source YouTube Music Feb 12 14:42:08 volumio volumio[12744]: info: Volumio Calling Home Feb 12 14:42:08 volumio sudo[12972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Feb 12 14:42:08 volumio sudo[12972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:08 volumio volumio[12878]: Starting albumart workers Feb 12 14:42:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Feb 12 14:42:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:08 volumio go-librespot[12977]: go-librespot daemon starting... Feb 12 14:42:08 volumio sudo[12972]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:08 volumio go-librespot[12978]: time="2026-02-12T14:42:08+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:09 volumio go-librespot[12978]: time="2026-02-12T14:42:09+07:00" level=info msg="zeroconf server listening on port 41579" Feb 12 14:42:09 volumio volumio[12744]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 14:42:09 volumio volumio[12744]: info: Discovery: Found device Volumio Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::volumioGetState Feb 12 14:42:09 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:09 volumio volumio[12744]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Feb 12 14:42:09 volumio volumio[12744]: info: Discovery: Found device Volumio Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::volumioGetState Feb 12 14:42:09 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:09 volumio volumio[12744]: info: MPD Permissions set Feb 12 14:42:09 volumio volumio[12744]: info: MPD Permissions set Feb 12 14:42:09 volumio volumio[12744]: info: Upmpdcli Daemon Started Feb 12 14:42:09 volumio volumio[12744]: info: Spotify config file written Feb 12 14:42:09 volumio volumio[12744]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 12 14:42:09 volumio sudo[12987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 14:42:09 volumio sudo[12987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:09 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 12 14:42:09 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 12 14:42:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:10 volumio go-librespot[12991]: go-librespot daemon starting... Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio sudo[12987]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:10 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=debug msg="app state loaded" Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:10 volumio volumio[12744]: info: No need to fix Spotify hosts Feb 12 14:42:10 volumio volumio[12744]: info: Volumio called home Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 14:42:10 volumio go-librespot[12996]: time="2026-02-12T14:42:10+07:00" level=info msg="zeroconf server listening on port 41967" Feb 12 14:42:11 volumio go-librespot[12996]: time="2026-02-12T14:42:11+07:00" level=debug msg="obtained new client token: AACxTvLXki251GsRKPz+yfQQZn/IKmGpBUEm3ISs7ufEkB3iZP9UNoGVT68TIVe5y21P2ZtVuU+VfLKXH54zxgABGqj9vY6+Kdo0IsF4jGL2VxCbYsUOrTmNZlnpE29jus7gZKPQEh7q0XKk7g5OKv5BuMlF09eWmHSFcV49MHyOZUsX2GpbbZJVspXn3dOgdMHXUEbDqSIuv4we8TOjmFf9bJPL6yb5P9a3uno6GbrMIwJiGe67EZo=" Feb 12 14:42:11 volumio go-librespot[12996]: time="2026-02-12T14:42:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:42:11 volumio volumio[12744]: info: Starting Shairport Sync Feb 12 14:42:11 volumio volumio[12744]: info: Starting Shairport Sync Feb 12 14:42:11 volumio go-librespot[12996]: time="2026-02-12T14:42:11+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:42:11 volumio volumio[12744]: info: Starting Shairport Sync Feb 12 14:42:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:11 volumio sudo[13027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 14:42:11 volumio sudo[13027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:11 volumio sudo[13029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 14:42:11 volumio sudo[13029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:11 volumio sudo[13031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 14:42:11 volumio sudo[13031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 14:42:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 14:42:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 14:42:11 volumio systemd[1]: shairport-sync.service: Consumed 2.327s CPU time. Feb 12 14:42:11 volumio volumio[12744]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 14:42:11 volumio volumio[12744]: SPOTIFY: BQDLTZyLoDXl3t0aXG7-MhCiO7dE0RhKv4_12R4Y57e-ly8XxLZ975aNTSVA9SVnhO1uRqw9MMkVlhXcnWaT-drF46VeAawOY2r3A8_0L5S_3uNQKFNStpQ-X9-SMQ2OM7eNh6ikye-5Urt74psBmQd3Q55K8_MDX0X_mMLwr2v_6Z1YpvRy7ekDOmYHeDmeMaWjrc1gVqTrq019iUCibo1dBNyqttIiBUyyz2L4cJzFn7sEG6Rtw2orNBSdPaa9Q3PDeMxICiDWhWQ4ZccH6_YZ5qJptGirtYdEu3A1V9tnrToAA94-EjeK Feb 12 14:42:11 volumio volumio[12744]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 14:42:11 volumio volumio[12744]: info: New Spotify access token = BQDLTZyLoDXl3t0aXG7-MhCiO7dE0RhKv4_12R4Y57e-ly8XxLZ975aNTSVA9SVnhO1uRqw9MMkVlhXcnWaT-drF46VeAawOY2r3A8_0L5S_3uNQKFNStpQ-X9-SMQ2OM7eNh6ikye-5Urt74psBmQd3Q55K8_MDX0X_mMLwr2v_6Z1YpvRy7ekDOmYHeDmeMaWjrc1gVqTrq019iUCibo1dBNyqttIiBUyyz2L4cJzFn7sEG6Rtw2orNBSdPaa9Q3PDeMxICiDWhWQ4ZccH6_YZ5qJptGirtYdEu3A1V9tnrToAA94-EjeK Feb 12 14:42:11 volumio volumio[12744]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 14:42:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 14:42:11 volumio sudo[13029]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:11 volumio sudo[13027]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:11 volumio sudo[13031]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:11 volumio volumio[12744]: info: CoreCommandRouter::volumioGetState Feb 12 14:42:11 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:11 volumio volumio[12744]: info: Shairport-Sync Started Feb 12 14:42:11 volumio volumio[12744]: Error adding Membership: Error: addMembership EINVAL Feb 12 14:42:11 volumio volumio[12744]: info: Shairport-Sync Started Feb 12 14:42:11 volumio volumio[12744]: info: Shairport-Sync Started Feb 12 14:42:12 volumio volumio[12744]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Feb 12 14:42:12 volumio volumio[12744]: info: Spotify Successfully logged in Feb 12 14:42:12 volumio volumio[12744]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 14:42:12 volumio volumio[12744]: info: [1770882132208] CoreMusicLibrary::Adding element Spotify Feb 12 14:42:12 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 14:42:12 volumio volumio[12744]: Cannot find translation for source YouTube Music Feb 12 14:42:12 volumio volumio[12744]: Cannot find translation for source Spotify Feb 12 14:42:13 volumio volumio[12744]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 12 14:42:13 volumio volumio[12744]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 14:42:13 volumio volumio[12744]: info: VolumeController:: Volume=100 Mute =false Feb 12 14:42:13 volumio volumio[12744]: info: CoreCommandRouter::volumioGetState Feb 12 14:42:13 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:13 volumio volumio[12744]: info: CoreStateMachine::pushState Feb 12 14:42:13 volumio volumio[12744]: info: CorePlayQueue::getTrack 0 Feb 12 14:42:13 volumio volumio[12744]: info: CoreCommandRouter::volumioPushState Feb 12 14:42:14 volumio mpd[12970]: 2026-02-12T14:42:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 14:42:14 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 14:42:14 volumio sudo[12931]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:14 volumio sudo[12941]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:14 volumio volumio[12744]: info: go-librespot daemon successfully initialized Feb 12 14:42:14 volumio volumio[12744]: error: MPD error: The expression evaluated to a falsy value: Feb 12 14:42:14 volumio volumio[12744]: assert.ok(self.idling) Feb 12 14:42:14 volumio volumio[12744]: error: The expression evaluated to a falsy value: Feb 12 14:42:14 volumio volumio[12744]: assert.ok(self.idling) Feb 12 14:42:14 volumio volumio[12744]: info: MPD running with PID12970 Feb 12 14:42:14 volumio volumio[12744]: ,establishing connection Feb 12 14:42:14 volumio volumio[12744]: error: updateQueue error: null Feb 12 14:42:14 volumio volumio[12744]: info: Completed starting Core Plugins Feb 12 14:42:14 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:42:14 volumio volumio[12744]: info: ----- MyVolumio plugins startup ---- Feb 12 14:42:14 volumio volumio[12744]: info: ------------------------------------------- Feb 12 14:42:14 volumio volumio[12744]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 14:42:14 volumio volumio[12744]: error: updateQueue error: null Feb 12 14:42:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 12 14:42:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:14 volumio go-librespot[13072]: go-librespot daemon starting... Feb 12 14:42:14 volumio go-librespot[13073]: time="2026-02-12T14:42:14+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:14 volumio go-librespot[13073]: time="2026-02-12T14:42:14+07:00" level=debug msg="app state loaded" Feb 12 14:42:14 volumio go-librespot[13073]: time="2026-02-12T14:42:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=info msg="zeroconf server listening on port 35411" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="obtained new client token: AADYL/sm8pfFsfIhC6PNucrjhkp9Zu9ZpsDW7mzt8Ss30tDZA9U8aN8ZoyafFaiXLFKOMPNxojxSN7fY/pJNC9ifKBYLu4iUUskM2XuXXk6S5c0+rqi9SEqMQ8mgFZNB4bOpQfC6uQi3YsNQfGnLvYYqK1ACCwlL8xmW0TxOX44T1f5hAZW4DAox43jlXmqCjwDVMU/MgdGgl1yPB9Y0XOriUCv4v3YnoBIVmWI+qxNMlB2kSlO/a77arQ==" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="completed keyexchange" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07:00" level=debug msg="completed challenge" Feb 12 14:42:15 volumio go-librespot[13073]: time="2026-02-12T14:42:15+07: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 12 14:42:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:17 volumio volumio[12744]: info: Initializing connection to go-librespot Websocket Feb 12 14:42:17 volumio volumio[12744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 14:42:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 12 14:42:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:18 volumio go-librespot[13080]: go-librespot daemon starting... Feb 12 14:42:18 volumio go-librespot[13081]: time="2026-02-12T14:42:18+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:18 volumio go-librespot[13081]: time="2026-02-12T14:42:18+07:00" level=debug msg="app state loaded" Feb 12 14:42:18 volumio go-librespot[13081]: time="2026-02-12T14:42:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:19 volumio volumio[12744]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=info msg="zeroconf server listening on port 39509" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=debug msg="obtained new client token: AABMJ1JlqFH/ntcR2Kd3OSEwdxgALkmpoNPAuvQT65T2eMYPPAnUbIf9t69RTocXW2UoW+a8R5Pdb/+ILoHfnEVyFb4VPaKNqmhkY3inPHLwmFehQTNsExecLGScqv9wvwTDYkaHSamRgFRhqpGWUaUrADSHpkMkzMgcEV8t0wHk5Owk0it2ps63P5yTx/G5Rkwpcb7IXdz5fM/hgGkbPnJIVycNYFbav7mAuvHSyOpXV516eZCudEymQQ==" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 12 14:42:19 volumio go-librespot[13081]: time="2026-02-12T14:42:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:42:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:20 volumio volumio[12744]: info: Initializing connection to go-librespot Websocket Feb 12 14:42:20 volumio volumio[12744]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 14:42:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 12 14:42:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:23 volumio go-librespot[13089]: go-librespot daemon starting... Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=debug msg="app state loaded" Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:23 volumio volumio[12744]: info: Initializing connection to go-librespot Websocket Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=debug msg="new websocket client" Feb 12 14:42:23 volumio volumio[12744]: info: Connection to go-librespot Websocket established Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin bluetooth to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin multiroom to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin metavolumio to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin cd_controller to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 12 14:42:23 volumio volumio[12744]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=info msg="zeroconf server listening on port 34241" Feb 12 14:42:23 volumio go-librespot[13090]: time="2026-02-12T14:42:23+07:00" level=debug msg="obtained new client token: AACC7nJK1H9l3x8Z27J9szjbDSsou8VSzGFlXO4HQMHpUh44KbGgMb4cjtyut+yitaVs/bhdPJurW967ZBkbP++jS7+UD6gJ3F2WrTCDhCwY1N8dcKzmv0aiV51yzL1MvwVj1sD1/+GNHwUpGM3agLmRIS7uzvS8bPbH/S/Tp4AVg2MOi2HZ4LIVI2j36jtYBJPbcnfYcxYc8TrS0xrLams96I+33n5ltzeRQjGS7sRh5o+Mu1EHXzXGpw==" Feb 12 14:42:24 volumio go-librespot[13090]: time="2026-02-12T14:42:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:42:24 volumio go-librespot[13090]: time="2026-02-12T14:42:24+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 12 14:42:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:25 volumio volumio[12744]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 14:42:25 volumio volumio[12744]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 14:42:25 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:25 volumio volumio[12744]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 14:42:25 volumio volumio[12744]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 14:42:25 volumio volumio[12744]: info: MyVolumio login type: Token Feb 12 14:42:25 volumio volumio[12744]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 14:42:25 volumio volumio[12744]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 14:42:27 volumio volumio[12744]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 14:42:27 volumio volumio[12744]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 14:42:27 volumio volumio[12744]: info: Streaming services startup Feb 12 14:42:27 volumio volumio[12744]: info: Starting Streaming Daemon Feb 12 14:42:27 volumio sudo[13112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 14:42:27 volumio sudo[13112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 14:42:27 volumio volumio[12744]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 14:42:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 12 14:42:27 volumio sudo[13112]: pam_unix(sudo:session): session closed for user root Feb 12 14:42:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:27 volumio volumio[12744]: info: Getting Spotify volume Feb 12 14:42:27 volumio volumio[12744]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:42:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 14:42:27 volumio go-librespot[13118]: go-librespot daemon starting... Feb 12 14:42:27 volumio volumio[12744]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 14:42:27 volumio volumio[12744]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 14:42:27 volumio volumio[12744]: errno: -111, Feb 12 14:42:27 volumio volumio[12744]: code: 'ECONNREFUSED', Feb 12 14:42:27 volumio volumio[12744]: syscall: 'connect', Feb 12 14:42:27 volumio volumio[12744]: address: '127.0.0.1', Feb 12 14:42:27 volumio volumio[12744]: port: 9879, Feb 12 14:42:27 volumio volumio[12744]: response: undefined Feb 12 14:42:27 volumio volumio[12744]: } Feb 12 14:42:27 volumio volumio[12744]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 14:42:27 volumio go-librespot[13120]: time="2026-02-12T14:42:27+07:00" level=info msg="running go-librespot 0.4.0" Feb 12 14:42:27 volumio go-librespot[13120]: time="2026-02-12T14:42:27+07:00" level=debug msg="app state loaded" Feb 12 14:42:27 volumio go-librespot[13120]: time="2026-02-12T14:42:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=info msg="zeroconf server listening on port 36513" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="obtained new client token: AABxogYjpKlXjbwiBahVSUeBAcFtGimms+pi4nTMXw84+UNUHBBntPMj39s/9XKxGM3wuYqIWIocthwVsFyeDs/Gaj0swHLhPQ/w8G7KPU8j8/kKeRVP2aNYq2l5oomGYI6XV8L020X9B73LSmkVKndMLNPLxFS7YE6fFKPz2iFGeSJGQn3OPiMuSciRXDr1vh3lDISx+zjKii+DBACoWcug22/rp5Vms/eeB9Xrl66YdDzwl5n10Lw=" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="completed keyexchange" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07:00" level=debug msg="completed challenge" Feb 12 14:42:28 volumio go-librespot[13120]: time="2026-02-12T14:42:28+07: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 12 14:42:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 14:42:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 14:42:28 volumio sudo[13141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 14:41' Feb 12 14:42:28 volumio sudo[13141]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"