Feb 23 21:11:00 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 23 21:11:01 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 23 21:11:01 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 23 21:11:25 volumio go-librespot[23474]: time="2026-02-23T21:11:25-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 23 21:11:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:25 volumio volumio[22737]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Feb 23 21:11:25 volumio volumio[22737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 21:11:25 volumio volumio[22737]: Error: socket hang up Feb 23 21:11:25 volumio volumio[22737]: at connResetException (node:internal/errors:720:14) Feb 23 21:11:25 volumio volumio[22737]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 23 21:11:25 volumio volumio[22737]: at Socket.emit (node:events:526:35) Feb 23 21:11:25 volumio volumio[22737]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 23 21:11:25 volumio volumio[22737]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 23 21:11:25 volumio volumio[22737]: code: 'ECONNRESET', Feb 23 21:11:25 volumio volumio[22737]: response: undefined Feb 23 21:11:25 volumio volumio[22737]: } Feb 23 21:11:25 volumio volumio[22737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 21:11:26 volumio sudo[24515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 21:10' Feb 23 21:11:26 volumio sudo[24515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:26 volumio sudo[24515]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:26 volumio volumio-remote-updater[26837]: [2026-02-23 21:11:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 21:11:26 volumio volumio-remote-updater[26837]: [2026-02-23 21:11:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 21:11:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 21:11:26 volumio systemd[1]: volumio.service: Consumed 55.448s CPU time. Feb 23 21:11:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 23 21:11:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 23 21:11:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3844. Feb 23 21:11:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 23 21:11:26 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 23 21:11:26 volumio systemd[1]: volumio.service: Consumed 55.448s CPU time. Feb 23 21:11:26 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 23 21:11:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 23 21:11:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Feb 23 21:11:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:28 volumio go-librespot[24571]: go-librespot daemon starting... Feb 23 21:11:28 volumio go-librespot[24572]: time="2026-02-23T21:11:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:28 volumio go-librespot[24572]: time="2026-02-23T21:11:28-06:00" level=debug msg="app state loaded" Feb 23 21:11:28 volumio go-librespot[24572]: time="2026-02-23T21:11:28-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:28 volumio go-librespot[24572]: time="2026-02-23T21:11:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:28 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:28 volumio volumio[24555]: info: ----- Volumio3 ---- Feb 23 21:11:28 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:28 volumio volumio[24555]: info: ----- System startup ---- Feb 23 21:11:28 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=info msg="zeroconf server listening on port 40499" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="obtained new client token: AADBojedAPWxgOA98wO01N8IyCml0LI/v1iJMTQcC7BkcqJzjv9mKlcaUygPdKgR5pAKXR1Vr6dGu5699DfdB+ddP1VYRNA3glccnQ6seEQns4x+/1kUWuE8BfPLueLNMGVhtnUsSCpB7/ulI1qa6pAyz76Cy5V7n1VpwKCGbLH+MRbZ95gqL47mNs8OyW2L0ZK3gl2DQS5pvk1S/yywO61y4yaFCmhuMkTyQrm6YHSdo7hpdzG9hJ4=" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=debug msg="completed challenge" Feb 23 21:11:29 volumio go-librespot[24572]: time="2026-02-23T21:11:29-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:29 volumio volumio[24555]: info: MYVOLUMIO Environment detected Feb 23 21:11:29 volumio volumio[24555]: info: Plugin folders cleanup Feb 23 21:11:29 volumio volumio[24555]: info: Scanning into folder /volumio/app/plugins/ Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category audio_interface Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category miscellanea Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category music_service Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category plugins.json Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category system_controller Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category user_interface Feb 23 21:11:29 volumio volumio[24555]: info: Scanning into folder /data/plugins/ Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category audio_interface Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category music_service Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category system_controller Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category system_hardware Feb 23 21:11:29 volumio volumio[24555]: info: Scanning category user_interface Feb 23 21:11:29 volumio volumio[24555]: info: Plugin folders cleanup completed Feb 23 21:11:29 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:29 volumio volumio[24555]: info: ----- Core plugins startup ---- Feb 23 21:11:29 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:29 volumio volumio[24555]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 21:11:29 volumio volumio[24555]: info: Adding plugin upnp to MyMusic Plugins Feb 23 21:11:29 volumio volumio[24555]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 21:11:29 volumio volumio[24555]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 21:11:29 volumio volumio[24555]: info: Loading plugins from folder /data/plugins/ Feb 23 21:11:29 volumio volumio[24555]: info: Loading plugin "system"... Feb 23 21:11:29 volumio volumio[24555]: info: Loading plugin "appearance"... Feb 23 21:11:30 volumio volumio[24555]: info: Loading plugin "network"... Feb 23 21:11:30 volumio volumio[24555]: info: Refreshing Cached IP Addresses Feb 23 21:11:30 volumio sudo[24592]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 21:11:30 volumio sudo[24592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:30 volumio sudo[24594]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 21:11:30 volumio volumio[24555]: info: Loading plugin "services"... Feb 23 21:11:30 volumio sudo[24594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:30 volumio sudo[24592]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "alsa_controller"... Feb 23 21:11:31 volumio sudo[24594]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:31 volumio sudo[24601]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 21:11:31 volumio sudo[24601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:31 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "wizard"... Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "networkfs"... Feb 23 21:11:31 volumio volumio[24555]: info: Starting Udev Watcher for removable devices Feb 23 21:11:31 volumio volumio[24555]: info: Ignoring mount for partition: boot Feb 23 21:11:31 volumio volumio[24555]: info: Ignoring mount for partition: volumio Feb 23 21:11:31 volumio volumio[24555]: info: Ignoring mount for partition: volumio_data Feb 23 21:11:31 volumio volumio[24555]: info: Mounting Device Wikipedia Feb 23 21:11:31 volumio sudo[24631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 23 21:11:31 volumio sudo[24631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:31 volumio sudo[24631]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:31 volumio volumio[24555]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 23 21:11:31 volumio volumio[24555]: dmesg(1) may have more information after failed mount system call. Feb 23 21:11:31 volumio volumio[24555]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 23 21:11:31 volumio volumio[24555]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 23 21:11:31 volumio volumio[24555]: dmesg(1) may have more information after failed mount system call. Feb 23 21:11:31 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "volumio_command_line_client"... Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "upnp"... Feb 23 21:11:31 volumio volumio[24555]: info: [1771902691351] Starting Upmpd Daemon Feb 23 21:11:31 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "my_music"... Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "mpd"... Feb 23 21:11:31 volumio volumio-remote-updater[26837]: [2026-02-23 21:11:31] [connect] Successful connection Feb 23 21:11:31 volumio sudo[24601]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:31 volumio volumio[24555]: info: Loading plugin "upnp_browser"... Feb 23 21:11:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Feb 23 21:11:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:32 volumio go-librespot[24633]: go-librespot daemon starting... Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=debug msg="app state loaded" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:11:32 volumio go-librespot[24634]: time="2026-02-23T21:11:32-06:00" level=info msg="zeroconf server listening on port 38629" Feb 23 21:11:33 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 23 21:11:33 volumio go-librespot[24634]: time="2026-02-23T21:11:33-06:00" level=debug msg="obtained new client token: AADnXPhpGyA7GQidHnY94l+MJnejmFVZGZ82ZJNwQUvpSE0tU/7XS6XxcpfAjjAsnUBka+aMEqhXVM3oujwEpemss0cPVyk1+5E7HDs7YqnZpwX7LKnJPpxGNI4J573R07pJn59d7B5uw24NOJTCAGWjK3RAOoNDcU4/5B0IzW3fMfhhtK/XdCqsgVYH2eHAOWr12oxc3XjbQxlGGo5WL7/HPWMIgHHdF44Ye09JYo60muFhkHfM6rQ=" Feb 23 21:11:33 volumio go-librespot[24634]: time="2026-02-23T21:11:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:33 volumio go-librespot[24634]: time="2026-02-23T21:11:33-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:33 volumio go-librespot[24634]: time="2026-02-23T21:11:33-06:00" level=debug msg="completed challenge" Feb 23 21:11:33 volumio go-librespot[24634]: time="2026-02-23T21:11:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:34 volumio volumio[24555]: info: Loading plugin "alarm-clock"... Feb 23 21:11:34 volumio volumio[24555]: info: Loading plugin "airplay_emulation"... Feb 23 21:11:34 volumio volumio[24555]: info: Starting Shairport Sync Feb 23 21:11:34 volumio volumio[24555]: info: Loading plugin "last_100"... Feb 23 21:11:34 volumio volumio[24555]: info: Loading plugin "webradio"... Feb 23 21:11:34 volumio volumio[24555]: info: Loading plugin "i2s_dacs"... Feb 23 21:11:34 volumio volumio[24555]: info: I2S DAC not set, start Auto-detection Feb 23 21:11:34 volumio volumio[24555]: info: Loading plugin "volumiodiscovery"... Feb 23 21:11:34 volumio volumio[24555]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 21:11:34 volumio volumio[24555]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 21:11:34 volumio volumio[24555]: *** WARNING *** For more information see Feb 23 21:11:34 volumio volumio[24555]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 21:11:34 volumio volumio[24555]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 21:11:34 volumio volumio[24555]: *** WARNING *** For more information see Feb 23 21:11:34 volumio node[24555]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 21:11:34 volumio node[24555]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 21:11:34 volumio node[24555]: *** WARNING *** For more information see Feb 23 21:11:34 volumio node[24555]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 21:11:34 volumio node[24555]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 21:11:34 volumio node[24555]: *** WARNING *** For more information see Feb 23 21:11:34 volumio volumio[24555]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 21:11:35 volumio volumio[24555]: info: Discovery: Started advertising with name: Volumio Feb 23 21:11:35 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 21:11:35 volumio volumio[24555]: info: Loading plugin "bandcamp"... Feb 23 21:11:36 volumio volumio[24555]: info: Plugin calmradio is not enabled Feb 23 21:11:36 volumio volumio[24555]: info: Loading plugin "soundcloud"... Feb 23 21:11:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Feb 23 21:11:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:36 volumio go-librespot[24646]: go-librespot daemon starting... Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="app state loaded" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:36 volumio volumio[24555]: info: Loading plugin "spop"... Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=info msg="zeroconf server listening on port 38647" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="obtained new client token: AAAyIKeuVMV1iHL8UkwsCYaikPMhhTb90v9VqbygDz6u8KpFyXEeTokLebd3NYGhC1lzdqqxv+icFVDx0BHPSp2sJNTxZ53OMeTbhH3f0vQ6Wh/48RKM+T1BGu1j/OVfqVkO+Qk+RyOUiJabL4kMMo5ZbRrgUQQInMlVVPlY+Vcn/ctUi8opEYNliDp52N6EcJCwkyTebXQXHCaVd6rY8Ig97uLcWqF8klM7uL7dL0I3JtoNjX9cMlxDuA==" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:36 volumio go-librespot[24647]: time="2026-02-23T21:11:36-06:00" level=debug msg="completed challenge" Feb 23 21:11:37 volumio go-librespot[24647]: time="2026-02-23T21:11:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:37 volumio kernel: hwmon hwmon1: Voltage normalised Feb 23 21:11:37 volumio volumio[24555]: info: Loading plugin "squeezelite_mc"... Feb 23 21:11:38 volumio volumio[24555]: info: Loading plugin "youtube2"... Feb 23 21:11:39 volumio volumio[24555]: info: Loading plugin "ytcr"... Feb 23 21:11:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Feb 23 21:11:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:40 volumio go-librespot[24656]: go-librespot daemon starting... Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="app state loaded" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:40 volumio volumio[24555]: info: Loading plugin "ytmusic"... Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=info msg="zeroconf server listening on port 36261" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="obtained new client token: AAAcfm0SnIoZUppGZ4liexNHF43hYsiWw0OFdW07RNM1Of3W5tgXK30kFfUwpy7+5cAKJgOJoxZyJoF1hCj1Z2/SWmYmogc/cx+EQBZ/xYbuCqS7Ai6Q2Azy5x47pvSn8u5CH2XZvTRrQqU2Il3QUQqgxPg1xd1EBcj4F1gw1rYd5upOIi/odKVnqvacRRTexflA3E++btTQc2HHj6yK8gkMDNhYZl/AcD5ZEdewHvPLdy4CakouX9B21A==" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=debug msg="completed challenge" Feb 23 21:11:40 volumio go-librespot[24657]: time="2026-02-23T21:11:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:40 volumio volumio[24555]: info: Plugin now_playing is not enabled Feb 23 21:11:40 volumio volumio[24555]: info: Loading plugin "outputs"... Feb 23 21:11:40 volumio volumio[24555]: info: Loading plugin "albumart"... Feb 23 21:11:40 volumio volumio[24555]: info: Plugin example_plugin is not enabled Feb 23 21:11:40 volumio volumio[24555]: info: Loading plugin "inputs"... Feb 23 21:11:40 volumio volumio[24555]: info: Loading plugin "updater_comm"... Feb 23 21:11:41 volumio volumio[24555]: info: Plugin mpdemulation is not enabled Feb 23 21:11:41 volumio volumio[24555]: info: Loading plugin "rest_api"... Feb 23 21:11:41 volumio volumio[24555]: info: Loading plugin "websocket"... Feb 23 21:11:41 volumio volumio[24555]: info: Starting Socket.io Server version 1.7.4 Feb 23 21:11:41 volumio volumio[24555]: info: Plugin fusiondsp is not enabled Feb 23 21:11:41 volumio volumio[24555]: info: Plugin mpdoutput is not enabled Feb 23 21:11:41 volumio volumio[24555]: info: Plugin RoonBridge is not enabled Feb 23 21:11:41 volumio volumio[24555]: info: Loading plugin "podcast"... Feb 23 21:11:41 volumio volumio[24555]: info: ControllerPodcast::constructor Feb 23 21:11:41 volumio volumio[24555]: info: Loading plugin "volusonic"... Feb 23 21:11:41 volumio volumio[24664]: Forking 3 albumart workers Feb 23 21:11:42 volumio volumio[24555]: info: Applying required configuration parameters for plugin volusonic Feb 23 21:11:42 volumio volumio[24555]: info: Loading plugin "backup_restore"... Feb 23 21:11:43 volumio volumio[24688]: Starting albumart workers Feb 23 21:11:43 volumio volumio[24689]: Starting albumart workers Feb 23 21:11:43 volumio volumio[24690]: Starting albumart workers Feb 23 21:11:43 volumio volumio[24555]: info: Applying required configuration parameters for plugin backup_restore Feb 23 21:11:43 volumio volumio[24555]: info: Plugin rpi_eeprom_config is not enabled Feb 23 21:11:43 volumio volumio[24555]: info: Plugin rpi_eeprom_updater is not enabled Feb 23 21:11:43 volumio volumio[24555]: info: Loading plugin "scheduledrestart"... Feb 23 21:11:43 volumio volumio[24555]: info: Applying required configuration parameters for plugin scheduledrestart Feb 23 21:11:43 volumio volumio[24555]: info: Plugin Bluetoothremote is not enabled Feb 23 21:11:43 volumio volumio[24555]: info: Plugin music_services_shield is not enabled Feb 23 21:11:43 volumio volumio[24555]: info: Loading plugin "Systeminfo"... Feb 23 21:11:43 volumio volumio[24555]: info: Plugin peppymeterbasic is not enabled Feb 23 21:11:43 volumio volumio[24555]: info: Loading plugin "peppyspectrum"... Feb 23 21:11:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Feb 23 21:11:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:43 volumio go-librespot[24740]: go-librespot daemon starting... Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="app state loaded" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:44 volumio volumio-remote-updater[26837]: [2026-02-23 21:11:44] [connect] Successful connection Feb 23 21:11:44 volumio volumio[24555]: info: Loading i18n strings for locale en Feb 23 21:11:44 volumio volumio[24555]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 23 21:11:44 volumio volumio[24555]: Updating browse sources language Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::initPlayerControls Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: Express server listening on port 3000 Feb 23 21:11:44 volumio volumio[24555]: [Metrics] WebUI: 16s 42.78ms Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::resetVolumioState Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::getcurrentVolume Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=info msg="zeroconf server listening on port 43207" Feb 23 21:11:44 volumio sudo[24755]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 21:11:44 volumio sudo[24755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:44 volumio sudo[24755]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:44 volumio sudo[24753]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 21:11:44 volumio sudo[24753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:44 volumio sudo[24753]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:44 volumio volumio[24555]: info: Volumio Network Manager: Network status updated: 1 Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="obtained new client token: AACk/JbaFZdhSPWNyBNda4u1uVerE3Qt9FQIi+nSbdxKZL9FuwemXic7iMTFTayKviyMwIWSFWNhxtfPEcQnKXchteH3D6wqsc6vneFnB3MbRWp36gK6+1uZ4nfU41H9M0i2njXqhdG4K1RfL4va745euRjtb6gHhCOfL5YLjcve72CAP4XY14pnG+BmKhN4jYqZQohmqiQkhJw7ZrfJ11Hxynhd7XnUE8lA5IsYqIqf7YQDsMpeyKPshA==" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:44 volumio volumio-remote-updater[26837]: [2026-02-23 21:11:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771902704 101 Feb 23 21:11:44 volumio volumio[24555]: 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 23 21:11:44 volumio volumio[24555]: info: VolumeController:: Volume=100 Mute =false Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::pushState Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioPushState Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::updateTrackBlock Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrackBlock Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=debug msg="completed challenge" Feb 23 21:11:44 volumio volumio[24555]: info: Reloading queue from file Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::setRepeat null single undefined Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::pushState Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioPushState Feb 23 21:11:44 volumio go-librespot[24741]: time="2026-02-23T21:11:44-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::setRandom null Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::pushState Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioPushState Feb 23 21:11:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:44 volumio volumio[24555]: info: Setting Device type: Raspberry PI Feb 23 21:11:44 volumio volumio[24555]: info: VolumeController:: Volume=100 Mute =false Feb 23 21:11:44 volumio volumio[24555]: info: CoreStateMachine::pushState Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioPushState Feb 23 21:11:44 volumio volumio[24555]: info: Completed loading Core Plugins Feb 23 21:11:44 volumio volumio[24555]: info: Preparing to generate the ALSA configuration file Feb 23 21:11:44 volumio sudo[24769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 21:11:44 volumio sudo[24769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:44 volumio volumio[24555]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 23 21:11:44 volumio volumio[24555]: info: Discovery: Found device Volumio Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioGetState Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:44 volumio volumio[24555]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 23 21:11:44 volumio volumio[24555]: info: Discovery: Found device Volumio Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::volumioGetState Feb 23 21:11:44 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:44 volumio volumio[24555]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 23 21:11:44 volumio sudo[24769]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:44 volumio volumio[24555]: info: Reading ALSA contributions from plugins. Feb 23 21:11:44 volumio volumio[24555]: info: Upmpdcli Daemon Started Feb 23 21:11:44 volumio volumio[24555]: info: Asound.conf file unchanged, so no further update is needed Feb 23 21:11:44 volumio volumio[24555]: info: Output device has changed, restarting MPD Feb 23 21:11:44 volumio volumio[24555]: info: Output device has changed, restarting Shairport Sync Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:44 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:44 volumio sudo[24772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 21:11:44 volumio sudo[24772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:44 volumio sudo[24772]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:44 volumio sudo[24774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 21:11:44 volumio sudo[24774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:44 volumio volumio[24555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 21:11:45 volumio volumio[24555]: info: ___________ START PLUGINS ___________ Feb 23 21:11:45 volumio volumio[24555]: info: ControllerMpd::onStart: Initializing MPD Feb 23 21:11:45 volumio volumio[24555]: info: Creating MPD Configuration file Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 21:11:45 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705040] CoreMusicLibrary::Adding element Media Servers Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio sudo[24782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 21:11:45 volumio sudo[24782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:45 volumio sudo[24782]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:45 volumio sudo[24784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 21:11:45 volumio sudo[24784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:45 volumio volumio[24555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:45 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 21:11:45 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 21:11:45 volumio systemd[1]: mpd.service: Consumed 4.382s CPU time. Feb 23 21:11:45 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 21:11:45 volumio volumio[24555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 21:11:45 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 21:11:45 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705170] CoreMusicLibrary::Adding element Last_100 Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705176] CoreMusicLibrary::Adding element Webradio Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 21:11:45 volumio volumio[24555]: info: Initializing BBC Radios Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:45 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705245] CoreMusicLibrary::Adding element Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705261] CoreMusicLibrary::Adding element SoundCloud Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: info: Creating Spotify config file Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:45 volumio sudo[24809]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 21:11:45 volumio sudo[24809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 21:11:45 volumio sudo[24809]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:45 volumio volumio[24555]: info: [squeezelite_mc] Starting proxy server... Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705621] CoreMusicLibrary::Adding element YouTube2 Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705640] CoreMusicLibrary::Adding element YouTube Music Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube Music Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705662] CoreMusicLibrary::Adding element Podcast Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube Music Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Podcast Feb 23 21:11:45 volumio volumio[24555]: info: Loading i18n strings for locale en Feb 23 21:11:45 volumio volumio[24555]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 23 21:11:45 volumio volumio[24555]: Updating browse sources language Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube Music Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Podcast Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube Music Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Podcast Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:45 volumio volumio[24555]: info: [1771902705711] CoreMusicLibrary::Adding element Volusonic Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source YouTube Music Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Podcast Feb 23 21:11:45 volumio volumio[24555]: Cannot find translation for source Volusonic Feb 23 21:11:45 volumio volumio[24555]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 23 21:11:45 volumio volumio[24555]: info: Volumio Calling Home Feb 23 21:11:45 volumio volumio[24555]: info: [squeezelite_mc] Proxy server started on port 38393 Feb 23 21:11:45 volumio volumio[24555]: info: Preparing to generate the ALSA configuration file Feb 23 21:11:45 volumio volumio[24555]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 21:11:45 volumio volumio[24555]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 23 21:11:45 volumio volumio[24555]: info: Reading ALSA contributions from plugins. Feb 23 21:11:46 volumio volumio[24555]: info: MPD Permissions set Feb 23 21:11:46 volumio volumio[24555]: info: MPD Permissions set Feb 23 21:11:46 volumio volumio[24555]: info: Spotify config file written Feb 23 21:11:46 volumio sudo[24819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 21:11:46 volumio sudo[24819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio volumio[24555]: 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 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: No need to fix Spotify hosts Feb 23 21:11:46 volumio go-librespot[24827]: go-librespot daemon starting... Feb 23 21:11:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:46 volumio sudo[24819]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="app state loaded" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:46 volumio volumio[24555]: 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 23 21:11:46 volumio volumio[24555]: info: VolumeController:: Volume=100 Mute =false Feb 23 21:11:46 volumio volumio[24555]: info: CoreStateMachine::pushState Feb 23 21:11:46 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::volumioPushState Feb 23 21:11:46 volumio volumio[24555]: info: [squeezelite_mc] Server discovery started Feb 23 21:11:46 volumio volumio[24555]: info: [squeezelite_mc] Player finder started Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 23 21:11:46 volumio volumio[24555]: info: Volumio called home Feb 23 21:11:46 volumio volumio[24555]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 21:11:46 volumio volumio[24555]: SPOTIFY: BQCF5b2zS1_q3sJBgRh2dZOMQbRWqeniW4oMoPS-0LXODx5BdTQNVNsoGnsOzmPwUkVZlDMpmakoK0sZiQ8vVvGaPO94afgI30BpbGiOyAEv207FM40n29fZbhCUoKPbhwPgJlnbEsT1ZR3_KkIoIkOBMlme2ByvoRpGtptEYvV3FflTCdycsMLnGnWGY6lZ_AIL7yk2lg2_nna4BfpeC0Yp3WHzFOEzzd1mpDU Feb 23 21:11:46 volumio volumio[24555]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 23 21:11:46 volumio volumio[24555]: info: New Spotify access token = BQCF5b2zS1_q3sJBgRh2dZOMQbRWqeniW4oMoPS-0LXODx5BdTQNVNsoGnsOzmPwUkVZlDMpmakoK0sZiQ8vVvGaPO94afgI30BpbGiOyAEv207FM40n29fZbhCUoKPbhwPgJlnbEsT1ZR3_KkIoIkOBMlme2ByvoRpGtptEYvV3FflTCdycsMLnGnWGY6lZ_AIL7yk2lg2_nna4BfpeC0Yp3WHzFOEzzd1mpDU Feb 23 21:11:46 volumio volumio[24555]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=info msg="zeroconf server listening on port 36055" Feb 23 21:11:46 volumio volumio[24555]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 23 21:11:46 volumio volumio[24555]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 23 21:11:46 volumio volumio[24555]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 21:11:46 volumio volumio[24555]: info: Starting Shairport Sync Feb 23 21:11:46 volumio volumio[24555]: info: Starting Shairport Sync Feb 23 21:11:46 volumio volumio[24555]: info: Starting Shairport Sync Feb 23 21:11:46 volumio sudo[24845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 21:11:46 volumio sudo[24845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="obtained new client token: AAAXMltmjZDxREfQNAuZCpoauZNdbBqdJm6f0yXS7dladrTDgjV/mZxw2e1K2Zmhd0oCas7a2vZItErL1e8M/ogJKAgf/gcx9Qy5sZwrZXc0/bGjLNm9koJPyBWYrPEYJnE3C4TT3WiumEJOVwfzYJtrd7LZmtooF2mDi65izySNSJixcaNQe8+gkICUup53CL+VngF02vlBCcG9TXTnMyR9g6vqu3DIE8gO/nj4RYHBgcNAj80nsjmMEg==" Feb 23 21:11:46 volumio sudo[24850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 21:11:46 volumio sudo[24850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio sudo[24843]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 21:11:46 volumio sudo[24843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio sudo[24847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 21:11:46 volumio sudo[24847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio sudo[24843]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:46 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 21:11:46 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 21:11:46 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 21:11:46 volumio systemd[1]: shairport-sync.service: Consumed 4.294s CPU time. Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:46 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 21:11:46 volumio sudo[24845]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=debug msg="completed challenge" Feb 23 21:11:46 volumio volumio[24555]: info: Asound.conf file unchanged, so no further update is needed Feb 23 21:11:46 volumio volumio[24555]: info: Output device has changed, restarting MPD Feb 23 21:11:46 volumio volumio[24555]: info: Output device has changed, restarting Shairport Sync Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:46 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:46 volumio sudo[24850]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:46 volumio go-librespot[24832]: time="2026-02-23T21:11:46-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:46 volumio sudo[24857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 21:11:46 volumio sudo[24857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 21:11:46 volumio sudo[24859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 21:11:46 volumio sudo[24859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:46 volumio sudo[24857]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:46 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 21:11:46 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 21:11:46 volumio volumio[24555]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 21:11:46 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 21:11:46 volumio sudo[24847]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:47 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 21:11:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 21:11:47 volumio systemd[1]: mpd.service: Consumed 1.581s CPU time. Feb 23 21:11:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 21:11:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 21:11:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 21:11:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 21:11:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 21:11:47 volumio volumio[24555]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 23 21:11:47 volumio sudo[24899]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 23 21:11:47 volumio sudo[24899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:47 volumio volumio[24555]: info: MPD Permissions set Feb 23 21:11:47 volumio volumio[24555]: info: Shairport-Sync Started Feb 23 21:11:47 volumio volumio[24555]: Error adding Membership: Error: addMembership EINVAL Feb 23 21:11:47 volumio volumio[24555]: info: Shairport-Sync Started Feb 23 21:11:47 volumio volumio[24555]: info: Shairport-Sync Started Feb 23 21:11:47 volumio sudo[24899]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:47 volumio volumio[24555]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:47 volumio sudo[24904]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 23 21:11:47 volumio sudo[24904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:47 volumio sudo[24904]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::volumioGetState Feb 23 21:11:47 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:11:47 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 23 21:11:47 volumio sudo[24892]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 21:11:47 volumio sudo[24892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 21:11:47 volumio sudo[24892]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:47 volumio sudo[24913]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 21:11:47 volumio volumio[24555]: info: Starting Shairport Sync Feb 23 21:11:47 volumio sudo[24913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:47 volumio sudo[24917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 21:11:47 volumio sudo[24917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:47 volumio systemd[1]: Reloading. Feb 23 21:11:47 volumio volumio[24555]: SPOTIFY: User informations: {"display_name":"Clint Smith","external_urls":{"spotify":"https://open.spotify.com/user/clintsmith1"},"followers":{"href":null,"total":7},"href":"https://api.spotify.com/v1/users/clintsmith1","id":"clintsmith1","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee856520409d25d9cc210472a0fd","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b826520409d25d9cc210472a0fd","width":64}],"type":"user","uri":"spotify:user:clintsmith1"} Feb 23 21:11:47 volumio volumio[24555]: info: Spotify Successfully logged in Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 21:11:47 volumio volumio[24555]: info: [1771902707589] CoreMusicLibrary::Adding element Spotify Feb 23 21:11:47 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source Bandcamp Discover Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source SoundCloud Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source YouTube2 Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source YouTube Music Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source Podcast Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source Volusonic Feb 23 21:11:47 volumio volumio[24555]: Cannot find translation for source Spotify Feb 23 21:11:48 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:48 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:48 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:11:48 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 21:11:49 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 21:11:49 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 21:11:49 volumio sudo[24913]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:49 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:49 volumio sudo[24937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:49 volumio sudo[24937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:49 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 21:11:49 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 21:11:49 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 21:11:49 volumio systemd[1]: shairport-sync.service: Consumed 1.517s CPU time. Feb 23 21:11:49 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 21:11:49 volumio sudo[24917]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:49 volumio volumio[24555]: info: Shairport-Sync Started Feb 23 21:11:49 volumio volumio[24555]: info: go-librespot daemon successfully initialized Feb 23 21:11:49 volumio sudo[24937]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:49 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 23 21:11:49 volumio sudo[24954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 23 21:11:49 volumio sudo[24954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:49 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 23 21:11:49 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 23 21:11:49 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 23 21:11:49 volumio sudo[24954]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Feb 23 21:11:49 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:50 volumio go-librespot[24957]: go-librespot daemon starting... Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="app state loaded" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=info msg="zeroconf server listening on port 44203" Feb 23 21:11:50 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="obtained new client token: AACM+Vx7lVJKAeZPajbsdmuItg3gn5V4aC6dcuL4BGHaQJ03hwICZbO00EMwBdT6+nwLPSnJswTKdn5vCEI73gP4SM02uzZZRpW5rBOzakcl5dXTZ3RSZYFX3KwXjBgwl9seEYPUq1acz3EyJE46oyeyt4Vp9nMplykoBTmV3L1k+VwK9/a6Rj23ueli5UJ5TUUAbIUN6f4DcFp7ljRPkKg25cu4hppDx1A3oye5OGc+43NFI3KB57N37g==" Feb 23 21:11:50 volumio sudo[24966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:50 volumio sudo[24966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:50 volumio sudo[24966]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:50 volumio volumio[24555]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=debug msg="completed challenge" Feb 23 21:11:50 volumio sudo[24970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 23 21:11:50 volumio sudo[24970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:50 volumio sudo[24970]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:50 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 23 21:11:50 volumio go-librespot[24958]: time="2026-02-23T21:11:50-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:50 volumio sudo[24974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 23 21:11:50 volumio sudo[24974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:50 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 23 21:11:50 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 23 21:11:50 volumio sudo[24974]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:50 volumio mpd[24914]: 2026-02-23T21:11:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 21:11:51 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 21:11:51 volumio sudo[24774]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:51 volumio sudo[24859]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:51 volumio sudo[24784]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:51 volumio volumio[24555]: error: MPD error: The expression evaluated to a falsy value: Feb 23 21:11:51 volumio volumio[24555]: assert.ok(self.idling) Feb 23 21:11:51 volumio volumio[24555]: error: The expression evaluated to a falsy value: Feb 23 21:11:51 volumio volumio[24555]: assert.ok(self.idling) Feb 23 21:11:51 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:51 volumio volumio[24555]: error: MPD error: The expression evaluated to a falsy value: Feb 23 21:11:51 volumio volumio[24555]: assert.ok(self.idling) Feb 23 21:11:51 volumio volumio[24555]: error: The expression evaluated to a falsy value: Feb 23 21:11:51 volumio volumio[24555]: assert.ok(self.idling) Feb 23 21:11:51 volumio volumio[24555]: info: MPD running with PID24914 Feb 23 21:11:51 volumio volumio[24555]: ,establishing connection Feb 23 21:11:51 volumio volumio[24555]: error: updateQueue error: null Feb 23 21:11:51 volumio volumio[24555]: error: updateQueue error: null Feb 23 21:11:51 volumio sudo[24999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:51 volumio sudo[24999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:51 volumio sudo[24999]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:51 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:51 volumio sudo[25002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:51 volumio sudo[25002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:52 volumio sudo[25002]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:52 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:52 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:11:52 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:11:52 volumio sudo[25005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:52 volumio sudo[25005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:52 volumio sudo[25005]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:53 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:53 volumio sudo[25008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:53 volumio sudo[25008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:53 volumio sudo[25008]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Feb 23 21:11:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:53 volumio volumio[24555]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 23 21:11:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:53 volumio go-librespot[25010]: go-librespot daemon starting... Feb 23 21:11:53 volumio sudo[25012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 23 21:11:53 volumio sudo[25012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=debug msg="app state loaded" Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:53 volumio sudo[25012]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:53 volumio volumio[24555]: info: Completed starting Core Plugins Feb 23 21:11:53 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:53 volumio volumio[24555]: info: ----- MyVolumio plugins startup ---- Feb 23 21:11:53 volumio volumio[24555]: info: ------------------------------------------- Feb 23 21:11:53 volumio volumio[24555]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:11:53 volumio go-librespot[25013]: time="2026-02-23T21:11:53-06:00" level=info msg="zeroconf server listening on port 35377" Feb 23 21:11:54 volumio go-librespot[25013]: time="2026-02-23T21:11:54-06:00" level=debug msg="obtained new client token: AAA6a4ou1p9wgVvLyRPXyoIbuzEG3/SNjIGb6k5owZS83a6O6aTjL1iypGe7UR8KUTrFwNqoSbjU00WK8Ot1aVNSIbCNi+A/44cDbjz8R8XULBwYfeZI1PUE1vE5zDGlpzClngcoQgBZBKEbOZEH5JLkf8Q6lnvWx9LwuMBzN9+GomM+nK5jdHaZdarzIXBHqwebVWMA4lgmWS4uBTIexgA4yvkshtr9pMwfZmq+Fn4m5ZfscgfjJ/I=" Feb 23 21:11:54 volumio go-librespot[25013]: time="2026-02-23T21:11:54-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:54 volumio go-librespot[25013]: time="2026-02-23T21:11:54-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:54 volumio go-librespot[25013]: time="2026-02-23T21:11:54-06:00" level=debug msg="completed challenge" Feb 23 21:11:54 volumio go-librespot[25013]: time="2026-02-23T21:11:54-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:54 volumio sudo[25023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 23 21:11:54 volumio sudo[25023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:54 volumio sudo[25025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 23 21:11:54 volumio sudo[25025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:54 volumio sudo[25027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 23 21:11:54 volumio sudo[25027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:11:54 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 23 21:11:54 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 23 21:11:54 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 23 21:11:54 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 23 21:11:54 volumio sudo[25023]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:54 volumio sudo[25025]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:54 volumio mpd_monitor.sh[25031]: MPD Monitor Service: Starting MPD Monitor Service Feb 23 21:11:54 volumio volumio[24555]: info: Successfully started MPD Monitor Feb 23 21:11:54 volumio volumio[24555]: info: Successfully started MPD Monitor Feb 23 21:11:54 volumio sudo[25027]: pam_unix(sudo:session): session closed for user root Feb 23 21:11:54 volumio volumio[24555]: info: Successfully started MPD Monitor Feb 23 21:11:55 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:11:55 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:11:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Feb 23 21:11:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:11:57 volumio go-librespot[25035]: go-librespot daemon starting... Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="app state loaded" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="stored credentials not found" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=info msg="zeroconf server listening on port 34885" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="obtained new client token: AABsAHzBg0sZY5WfqjHUE5MjSslv4bLAaZEG5NLxJhXS2GCNEjNnLpXarqXebuKW92LQt2ozTRJ/IvrE/bfyJtjOOlQxZbM1sZLPuP/W9Uhrk7VF1DIr636S/f0sD2tyEGFThz20GN4fCQQMe1Fv4N+OVcB95B6HLcYEidkmdTtmQiVtbkuMjMEHyl7j/YkcmTKGXu5YaF72/Wa3UPBUHaHvcPhfijAXp9wbm+mtBlhipfF1Xg9dPra43g==" Feb 23 21:11:57 volumio go-librespot[25036]: time="2026-02-23T21:11:57-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:11:58 volumio go-librespot[25036]: time="2026-02-23T21:11:58-06:00" level=debug msg="completed keyexchange" Feb 23 21:11:58 volumio go-librespot[25036]: time="2026-02-23T21:11:58-06:00" level=debug msg="completed challenge" Feb 23 21:11:58 volumio go-librespot[25036]: time="2026-02-23T21:11:58-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:11:58 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:11:58 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:11:59 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:11:59 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Feb 23 21:12:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:01 volumio go-librespot[25043]: go-librespot daemon starting... Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="app state loaded" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=info msg="zeroconf server listening on port 46283" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="obtained new client token: AACpc/Uo/CDuRToQ/Rb2EaadcQjnDIc9h+LSD4hksBIFTtuKjm19Z8HIxV/yTqZ0P6IgMAnz4Pslz8bbw8BBaAa0dP/VwQbWcvMXHk4c2JHhaxnEryUmI19scEchkgtYMpTuOmp12JD+UraovxwAj0luz9pEIK9YEYjG2uwzeF8ymlZF2uYSGaumKWI7WqDiudEprvbEr1zuZLhZDccPZHlstM48nwWXvULRqzngNodVLyznhuLZLfWKlQ==" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=debug msg="completed challenge" Feb 23 21:12:01 volumio go-librespot[25057]: time="2026-02-23T21:12:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 21:12:02 volumio volumio[24555]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 21:12:02 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:12:02 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:12:02 volumio volumio[24555]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 21:12:03 volumio volumio[24555]: info: MyVolumio login type: Token Feb 23 21:12:03 volumio volumio[24555]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 21:12:03 volumio volumio[24555]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 21:12:03 volumio volumio[24555]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 21:12:03 volumio volumio[24555]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 21:12:03 volumio volumio[24555]: info: Streaming services startup Feb 23 21:12:03 volumio volumio[24555]: info: Starting Streaming Daemon Feb 23 21:12:03 volumio sudo[25068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 21:12:03 volumio sudo[25068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:12:03 volumio volumio[24555]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 21:12:03 volumio sudo[25068]: pam_unix(sudo:session): session closed for user root Feb 23 21:12:03 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:04 volumio volumio[24555]: error: Cannot start Volumio Streaming Daemon Feb 23 21:12:04 volumio volumio[24555]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 21:12:04 volumio volumio[24555]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 21:12:04 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:04 volumio volumio[24555]: error: MyVolumio FAILED LOGIN: Invalid assertion format. 3 dot separated segments required. Feb 23 21:12:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Feb 23 21:12:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:04 volumio go-librespot[25075]: go-librespot daemon starting... Feb 23 21:12:04 volumio go-librespot[25076]: time="2026-02-23T21:12:04-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:04 volumio go-librespot[25076]: time="2026-02-23T21:12:04-06:00" level=debug msg="app state loaded" Feb 23 21:12:04 volumio go-librespot[25076]: time="2026-02-23T21:12:04-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:04 volumio go-librespot[25076]: time="2026-02-23T21:12:04-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=info msg="zeroconf server listening on port 34157" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="obtained new client token: AABcXNvD8VaEqR+7+zo68Os2lyqh+wNFSs1mWd+e/GzFCRCJirGLtZfnEKSj+222J6AxedL7q2TAQ2fwC2pR9OZn0NLpDbHwZMdZpK1UPam1S9R5sAfJzvq1bbrs6uq2k9ofbfJLc7MzE624WKxQZtCXM9SbvBzpSQQhGsy/M/ESpLaWBBBkox6IFMQ2YMMD70d4AyPqOxCJBNCLFXCc55ey3ynD1PhWfUqkKZMDB9TWBTE1RZRy50lLqA==" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=debug msg="completed challenge" Feb 23 21:12:05 volumio go-librespot[25076]: time="2026-02-23T21:12:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:07 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:07 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Feb 23 21:12:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:08 volumio go-librespot[25083]: go-librespot daemon starting... Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=debug msg="app state loaded" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:08 volumio go-librespot[25084]: time="2026-02-23T21:12:08-06:00" level=info msg="zeroconf server listening on port 45563" Feb 23 21:12:09 volumio go-librespot[25084]: time="2026-02-23T21:12:09-06:00" level=debug msg="obtained new client token: AACUZf3//6qvHFiwDLZv/OQGUtNP1Gg6Dkdh4Vq8zNPU9hdLmAvSC0ddBMoQvilnZwWxwFAtfaWwLMIJVw6jGdKdeWvMjoYIRqFfueoNK4qmbvf/Uj193nL8XKvSi+Y3bqNqO+RcTrOjaYr6ezgfmGbG1P8fNw8QCqY0OBBmWS2bSJY6PpUKt/RQ4O7ObvuOdDGphe87wuS2qK9+/zOZP5eILqkYIg56buZJfppLyKybCdxgLCqMoak=" Feb 23 21:12:09 volumio go-librespot[25084]: time="2026-02-23T21:12:09-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:09 volumio go-librespot[25084]: time="2026-02-23T21:12:09-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:09 volumio go-librespot[25084]: time="2026-02-23T21:12:09-06:00" level=debug msg="completed challenge" Feb 23 21:12:09 volumio go-librespot[25084]: time="2026-02-23T21:12:09-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:10 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:10 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Feb 23 21:12:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:12 volumio go-librespot[25105]: go-librespot daemon starting... Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="app state loaded" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=info msg="zeroconf server listening on port 41703" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="obtained new client token: AABWXTx5Ih61RRFPw1BBvKbwuXdQWZAzImjYr8KrFQXyzORkdXgeIoMkHV66rlp0MN/zwDfY1RPl6Wi6pDvMgEYG7JlOoVBzk6iPqKRYGvcfG0dkQUFbzdO/jUwoyD7hDbpUJ+o0HGnpfyvSGKljegW6bD92mP5/QA+CxNGCdwPNUH5rgUwZc2/fbbVPlL/qXIowyH4sXQfzm6j94rQ9Mvmtv0fHH7JCFWfHPg9P6sxBP0yXTh17C3S50A==" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=debug msg="completed challenge" Feb 23 21:12:12 volumio go-librespot[25106]: time="2026-02-23T21:12:12-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:13 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:13 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:15 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 21:12:15 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:12:15 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 21:12:15 volumio volumio-remote-updater[26837]: Test mode disabled Feb 23 21:12:15 volumio volumio-remote-updater[26837]: Alpha mode disabled Feb 23 21:12:15 volumio volumio-remote-updater[26837]: Alpha legacy test mode disabled Feb 23 21:12:15 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 23 21:12:15 volumio volumio[24555]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix for CPU utilization spike
    • \n
  • Fix for triggerhappy input permission
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add BLE onboarding plugin
    • \n
  • Enable vc4-kms-v3d for all compatible RPi models
    • \n
  • Update Tidal SDK
    • \n
\n","title":"Update v4.096","updateavailable":true} Feb 23 21:12:16 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 23 21:12:16 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:16 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 71. Feb 23 21:12:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:16 volumio go-librespot[25120]: go-librespot daemon starting... Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="app state loaded" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=info msg="zeroconf server listening on port 45181" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="obtained new client token: AADo7/8Cx1NCZsRJNELrT2siDY0bGwN146UjXdU78XueIdy+fqvAgUAonhzIhjhM/0NBKoNlOFNmnjCpg14HDQMxlCG0SteU1yNcyYD0OO8ekql8nbB/xbP2OJ5OL7T/yZ20m4Hx0dtTa3gwX+VIx9JfB607mChhZSTo19gqdXJknFhe4em/Y9E7MKtIzckkWu3o+G/5r6KJtJ9S2HXsB8Pxj/XaeyodQTjwJ+ywQZa815mc/TH9biujsg==" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=debug msg="completed challenge" Feb 23 21:12:16 volumio go-librespot[25121]: time="2026-02-23T21:12:16-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:17 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 21:12:18 volumio volumio[24555]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 23 21:12:18 volumio volumio[24555]: info: CoreCommandRouter::volumioGetState Feb 23 21:12:18 volumio volumio[24555]: info: CorePlayQueue::getTrack 0 Feb 23 21:12:19 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:19 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 72. Feb 23 21:12:19 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:19 volumio go-librespot[25128]: go-librespot daemon starting... Feb 23 21:12:19 volumio go-librespot[25129]: time="2026-02-23T21:12:19-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:19 volumio go-librespot[25129]: time="2026-02-23T21:12:19-06:00" level=debug msg="app state loaded" Feb 23 21:12:19 volumio go-librespot[25129]: time="2026-02-23T21:12:19-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:19 volumio go-librespot[25129]: time="2026-02-23T21:12:19-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=info msg="zeroconf server listening on port 45835" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="obtained new client token: AAAUFEWK7ztbehkhzQgECNr6it+SYZkAl2ug1X8VPxo5HfHHgKtya/AX0p9ZauhtPg3eLEdabeN9yk7AvTbGM+F35bg9uDrRn3CEosSehsL8ZpUGH3r1rXUIJU3HypeJhh1vO3nn1tzSY0lDQmtApVczkKu1DZZd4ZxGxBrHhmGZppYOq+RM8foXzKog1r/2/G74WJnKTZZfEHwk/44UPXAbbvntK3MdkN9wbujlTe+3syTY1kXa9YV7vg==" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=debug msg="completed challenge" Feb 23 21:12:20 volumio go-librespot[25129]: time="2026-02-23T21:12:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:22 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:22 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:22 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:12:22 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:12:22 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 23 21:12:22 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 23 21:12:22 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 23 21:12:22 volumio volumio[24555]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 21:12:23 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 21:12:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73. Feb 23 21:12:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:23 volumio go-librespot[25151]: go-librespot daemon starting... Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=debug msg="app state loaded" Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 21:12:23 volumio volumio[24555]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 23 21:12:23 volumio volumio[24555]: info: Completed starting MyVolumio Plugin Feb 23 21:12:23 volumio volumio[24555]: [Metrics] CommandRouter: 55s 13.24ms Feb 23 21:12:23 volumio volumio[24555]: info: CoreCommandRouter::volumiosetStartupVolume Feb 23 21:12:23 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 21:12:23 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 21:12:23 volumio go-librespot[25152]: time="2026-02-23T21:12:23-06:00" level=info msg="zeroconf server listening on port 43573" Feb 23 21:12:23 volumio volumio[24555]: info: CoreCommandRouter::Close All Modals sent Feb 23 21:12:23 volumio volumio[24555]: info: CoreCommandRouter::Close All Modals sent Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=debug msg="obtained new client token: AABBpnjLw2AeJnLAtKGAF+WeDCIQ0+7uMHlaChpxySfaLUwJZ7QP5gfCy8oMCsBnuPW/A2VONZ0P2G9pKwWTePfEbh1y7VcJtbyApvEj8jaabExTF9phTxvjgMignFL0llAhZTfLqLDJ+lLHpSHXKHhOfd/aqjcKTY5AgUfyzndebpwg+/nn71JpAyMEhvreK4eCOpwBgFeeTwOCS+V1MR4tI4LzxLdJNAyy/YKMfs9bwT2eNgjtTUU=" Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused" Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=debug msg="completed challenge" Feb 23 21:12:24 volumio go-librespot[25152]: time="2026-02-23T21:12:24-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:24 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 21:12:24 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 23 21:12:24 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 21:12:25 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:25 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74. Feb 23 21:12:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:27 volumio go-librespot[25165]: go-librespot daemon starting... Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="app state loaded" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=info msg="zeroconf server listening on port 43265" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="obtained new client token: AAArvBVGtm7MKVD+DBLy4QYffPc3+EAgdijxtElKYvxpqrr5Okg7/1B8CzUZpPhwO0GVrrCDUMM64mfMP+5D1TN0zkpdFUdN7AWU5DVB7uLG7fMnqrlGkyRuHUDjiT5uoFB5iBMWRuLLdUKeydTmx6E29L6furKairxEQE9S5M/YfQav/UHkgR0v5nkBgewAXRyUOG2wowyxu0Q9IDrHqC2CL9XpXNRLMM5KaAnYGURA+UmlfWQSI+Km8A==" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=debug msg="completed challenge" Feb 23 21:12:27 volumio go-librespot[25166]: time="2026-02-23T21:12:27-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:28 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:28 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:30 volumio volumio[24555]: info: BOOT COMPLETED Feb 23 21:12:31 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:31 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Feb 23 21:12:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:31 volumio go-librespot[25173]: go-librespot daemon starting... Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="app state loaded" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=info msg="zeroconf server listening on port 41049" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="obtained new client token: AAAVnOmCfB9G3ZFYzIIBRfxuqnA8YoA1OCSrD6GqdEwXw+YUQDXnrmAhNWhhDGGzuY0ViEYzkNAX1CFP0oruez7q0kHtJAjamdkvHBZnSZ4OXi5yvDDxf2IkTmOE8xJpZSGMaTlpTkfox9NMDW0BX+t7A/mX8qYWDBmsMIMl6cxts7TmTveg/b8OkY7e7t/SJlSjJShTV1h5lJgInnzSqi9Y5wGGEPLZd7Cqnf00BWCPZPC7xewg7Yg+Wg==" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=debug msg="completed challenge" Feb 23 21:12:31 volumio go-librespot[25174]: time="2026-02-23T21:12:31-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:34 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:34 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Feb 23 21:12:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:34 volumio go-librespot[25198]: go-librespot daemon starting... Feb 23 21:12:34 volumio go-librespot[25199]: time="2026-02-23T21:12:34-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:34 volumio go-librespot[25199]: time="2026-02-23T21:12:34-06:00" level=debug msg="app state loaded" Feb 23 21:12:34 volumio go-librespot[25199]: time="2026-02-23T21:12:34-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:34 volumio go-librespot[25199]: time="2026-02-23T21:12:34-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=info msg="zeroconf server listening on port 46781" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="obtained new client token: AAB5Grc61OQzKQa76IC95GaLaebPUgOyTKsRp6VztPn9hsQE98FFjSeMiZ+XRRVJRD1JnFPRwZOj4JgAjSO3HxuaQUs1gLxH1jolmLwtVPAukYN1BEivpDzzS1YgCWR3YaxvHY4kyEhLTqUPfsaNqTIRrxPQy+NNp7PNkwDBIBWEGD4CVhM1WLtspzxlOoQsloH7SgSI4X0SsoZOBUubFXQTR4QukcYYg6yDvM7QmNqyB+iQLu9YBvU=" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=debug msg="completed challenge" Feb 23 21:12:35 volumio go-librespot[25199]: time="2026-02-23T21:12:35-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:37 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:37 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Feb 23 21:12:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:38 volumio go-librespot[25206]: go-librespot daemon starting... Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=debug msg="app state loaded" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:38 volumio go-librespot[25207]: time="2026-02-23T21:12:38-06:00" level=info msg="zeroconf server listening on port 39803" Feb 23 21:12:39 volumio go-librespot[25207]: time="2026-02-23T21:12:39-06:00" level=debug msg="obtained new client token: AAAgILTj4BKltJeUhCuUjmAb0NahnHTPWBfrxPnr0EdbsytQq3X2hHZ3b3JksWLJ3+ywTwzssrkQ4g4Dy8xBOsHt5a0fGVmj09nBLuSPlslHqDs1R/0dXgz6WtNJa1g2SRQDTATD7Vzumn7BGuz/St+W73C2VSducrtkylDr/dv8cSQ/MpsbbdNt7MekO/BXg101ATs7so7VcOPD0ZTkdPNLLMU/d9aZcp79tlAq9f6JcxOgHNlaptI=" Feb 23 21:12:39 volumio go-librespot[25207]: time="2026-02-23T21:12:39-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:39 volumio go-librespot[25207]: time="2026-02-23T21:12:39-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:39 volumio go-librespot[25207]: time="2026-02-23T21:12:39-06:00" level=debug msg="completed challenge" Feb 23 21:12:39 volumio go-librespot[25207]: time="2026-02-23T21:12:39-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:40 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:40 volumio volumio[24555]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Feb 23 21:12:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:42 volumio go-librespot[25229]: go-librespot daemon starting... Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="app state loaded" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=info msg="zeroconf server listening on port 43817" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="obtained new client token: AAAK9rNlYywLXno8EZ6OeJ56iXHHWSMwraZBwlF6XavTPF2usi6uo9rd2P0z1caK8mh9tKgRaQVdn3fqf1WW3VZtPPeDs6LeTIA9mjd9s3KwEsVAAH3i51HZG8FtbQnC7zoQRqYEOcEjPHlQf7RROO/q/VuJTUAd4+o5YsiqlAm5X74rX4yLSV2CkHu/2h2++i1d5fFZfRh4Kg011I05bmoX9i9+eSpvJ+jPuEUz/OS1GOdwnz5AoPqo3A==" Feb 23 21:12:42 volumio go-librespot[25230]: time="2026-02-23T21:12:42-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 21:12:43 volumio volumio[24555]: info: Initializing connection to go-librespot Websocket Feb 23 21:12:43 volumio go-librespot[25230]: time="2026-02-23T21:12:43-06:00" level=debug msg="new websocket client" Feb 23 21:12:43 volumio volumio[24555]: info: Connection to go-librespot Websocket established Feb 23 21:12:43 volumio go-librespot[25230]: time="2026-02-23T21:12:43-06:00" level=debug msg="completed keyexchange" Feb 23 21:12:43 volumio go-librespot[25230]: time="2026-02-23T21:12:43-06:00" level=debug msg="completed challenge" Feb 23 21:12:43 volumio go-librespot[25230]: time="2026-02-23T21:12:43-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 21:12:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 21:12:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 21:12:43 volumio volumio[24555]: info: Connection to go-librespot Websocket closed Feb 23 21:12:45 volumio volumio[24555]: info: UPDATER: Scheduling automatic update Feb 23 21:12:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Feb 23 21:12:45 volumio volumio[24555]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Feb 23 21:12:45 volumio volumio[24555]: info: UPDATER: Auto update will take place at: Tue Feb 24 2026 04:50:24 GMT-0600 (Central Standard Time) Feb 23 21:12:46 volumio volumio[24555]: info: Getting Spotify volume Feb 23 21:12:46 volumio volumio[24555]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 21:12:46 volumio volumio[24555]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 21:12:46 volumio volumio[24555]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 23 21:12:46 volumio volumio[24555]: errno: -111, Feb 23 21:12:46 volumio volumio[24555]: code: 'ECONNREFUSED', Feb 23 21:12:46 volumio volumio[24555]: syscall: 'connect', Feb 23 21:12:46 volumio volumio[24555]: address: '127.0.0.1', Feb 23 21:12:46 volumio volumio[24555]: port: 9879, Feb 23 21:12:46 volumio volumio[24555]: response: undefined Feb 23 21:12:46 volumio volumio[24555]: } Feb 23 21:12:46 volumio volumio[24555]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 21:12:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Feb 23 21:12:46 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:46 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 21:12:46 volumio go-librespot[25249]: go-librespot daemon starting... Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=info msg="running go-librespot 0.4.0" Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=debug msg="app state loaded" Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=debug msg="stored credentials not found" Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 21:12:46 volumio sudo[25259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 21:11' Feb 23 21:12:46 volumio sudo[25259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 23 21:12:46 volumio go-librespot[25250]: time="2026-02-23T21:12:46-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 23 21:12:47 volumio go-librespot[25250]: time="2026-02-23T21:12:47-06:00" level=info msg="zeroconf server listening on port 39887" Feb 23 21:12:47 volumio go-librespot[25250]: time="2026-02-23T21:12:47-06:00" level=debug msg="obtained new client token: AADM3J4EUb9htZLoqrdedOcuJhSNoZkgRS5IDOMg8U97r3fvrwDv+XELv8XXm4NTfHZJAflenTIUhZae4oK9cb1oLeWBKJBH3UvFzbjzPPGnYr9MDxMY1uw3t92hw0iPTgnB2LZR9Ax0C5Ov04Xbejuvq6uvT/nbN2YyQmIxT/K/onIq/UuFUO3zDNB7CJZGCWkinZvQSDRtz2IGs0AMyMz4YJzzH5WcV3jnL4KWrcKtAo2cOj7B3c8=" 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="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"