Feb 12 18:23:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6798. Feb 12 18:23:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 18:23:00 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 18:23:00 volumio systemd[1]: volumio.service: Consumed 47.720s CPU time. Feb 12 18:23:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 18:23:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 18:23:02 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:02 volumio volumio[28950]: info: ----- Volumio3 ---- Feb 12 18:23:02 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:02 volumio volumio[28950]: info: ----- System startup ---- Feb 12 18:23:02 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 12 18:23:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:02 volumio go-librespot[28971]: go-librespot daemon starting... Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=debug msg="app state loaded" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-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 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-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 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-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 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=info msg="zeroconf server listening on port 46443" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=debug msg="obtained new client token: AADYYa/FxmkDnBf/lH8BdgHEXo41iwCYYOdsabsvWz3C4P/RwJ0HRse24rOp0gBSE+gRxvGiwJsV0o6vhHes5BTfVHiYl+FzOiXiSwZZS8dVeSc7mYBprWXOT83e215+vTLpv5k3cXM2Z0+7LCoNtC4YmvvB4/f/t0OwUsm6QzP52RApsuLsjgEfVWLjBe749X3tp4598M2anygd9Gj1z/gbl+ThE6/Rc53VyftT9hAe20Efi3tKCB783w==" Feb 12 18:23:02 volumio volumio[28950]: info: MYVOLUMIO Environment detected Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-06:00" level=debug msg="completed challenge" Feb 12 18:23:02 volumio volumio[28950]: info: Plugin folders cleanup Feb 12 18:23:02 volumio volumio[28950]: info: Scanning into folder /volumio/app/plugins/ Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category audio_interface Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category miscellanea Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category music_service Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category plugins.json Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category system_controller Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category user_interface Feb 12 18:23:02 volumio volumio[28950]: info: Scanning into folder /data/plugins/ Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category audio_interface Feb 12 18:23:02 volumio go-librespot[28972]: time="2026-02-12T18:23:02-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 12 18:23:02 volumio volumio[28950]: info: Scanning category music_service Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category system_controller Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category system_hardware Feb 12 18:23:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:02 volumio volumio[28950]: info: Scanning category user_interface Feb 12 18:23:02 volumio volumio[28950]: info: Plugin folders cleanup completed Feb 12 18:23:02 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:02 volumio volumio[28950]: info: ----- Core plugins startup ---- Feb 12 18:23:02 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:02 volumio volumio[28950]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 18:23:02 volumio volumio[28950]: info: Adding plugin upnp to MyMusic Plugins Feb 12 18:23:02 volumio volumio[28950]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 18:23:02 volumio volumio[28950]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 18:23:02 volumio volumio[28950]: info: Loading plugins from folder /data/plugins/ Feb 12 18:23:02 volumio volumio[28950]: info: Loading plugin "system"... Feb 12 18:23:03 volumio volumio[28950]: info: Loading plugin "appearance"... Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "network"... Feb 12 18:23:04 volumio volumio[28950]: info: Refreshing Cached IP Addresses Feb 12 18:23:04 volumio sudo[28986]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 18:23:04 volumio sudo[28986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:04 volumio sudo[28986]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:04 volumio sudo[28988]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 18:23:04 volumio sudo[28988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "services"... Feb 12 18:23:04 volumio sudo[28988]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "alsa_controller"... Feb 12 18:23:04 volumio sudo[28996]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 18:23:04 volumio sudo[28996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:04 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "wizard"... Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "networkfs"... Feb 12 18:23:04 volumio volumio[28950]: info: Starting Udev Watcher for removable devices Feb 12 18:23:04 volumio volumio[28950]: info: Ignoring mount for partition: boot Feb 12 18:23:04 volumio volumio[28950]: info: Ignoring mount for partition: volumio Feb 12 18:23:04 volumio volumio[28950]: info: Ignoring mount for partition: volumio_data Feb 12 18:23:04 volumio volumio[28950]: info: Mounting Device Wikipedia Feb 12 18:23:04 volumio sudo[29025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 12 18:23:04 volumio sudo[29025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:04 volumio sudo[29025]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:04 volumio volumio[28950]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 12 18:23:04 volumio volumio[28950]: dmesg(1) may have more information after failed mount system call. Feb 12 18:23:04 volumio volumio[28950]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 12 18:23:04 volumio volumio[28950]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 12 18:23:04 volumio volumio[28950]: dmesg(1) may have more information after failed mount system call. Feb 12 18:23:04 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "volumio_command_line_client"... Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "upnp"... Feb 12 18:23:04 volumio volumio[28950]: info: [1770942184477] Starting Upmpd Daemon Feb 12 18:23:04 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "my_music"... Feb 12 18:23:04 volumio volumio[28950]: info: Loading plugin "mpd"... Feb 12 18:23:04 volumio volumio-remote-updater[2265]: [2026-02-12 18:23:04] [connect] Successful connection Feb 12 18:23:04 volumio sudo[28996]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 18:23:05 volumio volumio[28950]: info: Loading plugin "upnp_browser"... Feb 12 18:23:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Feb 12 18:23:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:06 volumio go-librespot[29028]: go-librespot daemon starting... Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=debug msg="app state loaded" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-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 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-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 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-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 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=info msg="zeroconf server listening on port 37963" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=debug msg="obtained new client token: AACmTeMT2wWy61iyFD+Ij8lwhGqecoiRYDhgVpFdw4cY1mOm8Ay+z5aHaLc/ykzfsSB5sCXQTfWa/0bXrCRTzhYyN/ofyo2jV0UgijSvaqGGUcQHhTjJUncVoqsy4Mu2Ut9cZ3BfoE/nZC/2MvKDuo7j8sB+HXc+j+srMyXXNVgDw6OGDTkKivVUd4kROJ779Frm40eeBjvFdY0JHI6kBGsT8WNW9UEavVeQMWtLPoXo2yHWWnaze8tOKQ==" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-06:00" level=debug msg="completed challenge" Feb 12 18:23:06 volumio go-librespot[29029]: time="2026-02-12T18:23:06-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 12 18:23:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:07 volumio volumio[28950]: info: Loading plugin "alarm-clock"... Feb 12 18:23:07 volumio volumio[28950]: info: Loading plugin "airplay_emulation"... Feb 12 18:23:07 volumio volumio[28950]: info: Starting Shairport Sync Feb 12 18:23:07 volumio volumio[28950]: info: Loading plugin "last_100"... Feb 12 18:23:07 volumio volumio[28950]: info: Loading plugin "webradio"... Feb 12 18:23:08 volumio volumio[28950]: info: Loading plugin "i2s_dacs"... Feb 12 18:23:08 volumio volumio[28950]: info: I2S DAC not set, start Auto-detection Feb 12 18:23:08 volumio volumio[28950]: info: Loading plugin "volumiodiscovery"... Feb 12 18:23:08 volumio volumio[28950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:08 volumio volumio[28950]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:08 volumio volumio[28950]: *** WARNING *** For more information see Feb 12 18:23:08 volumio volumio[28950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:08 volumio volumio[28950]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:08 volumio volumio[28950]: *** WARNING *** For more information see Feb 12 18:23:08 volumio node[28950]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:08 volumio node[28950]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:08 volumio node[28950]: *** WARNING *** For more information see Feb 12 18:23:08 volumio node[28950]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:08 volumio node[28950]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:08 volumio node[28950]: *** WARNING *** For more information see Feb 12 18:23:08 volumio volumio[28950]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 18:23:08 volumio volumio[28950]: info: Discovery: Started advertising with name: Volumio Feb 12 18:23:08 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 18:23:08 volumio volumio[28950]: info: Loading plugin "bandcamp"... Feb 12 18:23:09 volumio volumio[28950]: info: Plugin calmradio is not enabled Feb 12 18:23:09 volumio volumio[28950]: info: Loading plugin "soundcloud"... Feb 12 18:23:09 volumio volumio[28950]: info: Loading plugin "spop"... Feb 12 18:23:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Feb 12 18:23:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:10 volumio go-librespot[29041]: go-librespot daemon starting... Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=debug msg="app state loaded" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-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 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-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 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-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 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=info msg="zeroconf server listening on port 45829" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=debug msg="obtained new client token: AAA4hz+0o9KD89IS3qeHzrkG528VFOYkV/oIbGX3h3vJW2QmVw0A2HokIsvZDVII5zl1cguj4xcyyhT4akpA+Y5esQduhed7p5LPqxBySWpNwovSR9h6/OwZPwSQTpSxOLr/QJJI9O4HdMBXDdKK8tQXG/P/2omcCHlYsT3EVnYKasn6pWsD3qDGKg7K6JY+0b0uxxmRoGG6OdyB0ma4CXVGTRIoY90sGmQRdKJMhhWxInxzkbpLxVIw/g==" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-06:00" level=debug msg="completed challenge" Feb 12 18:23:10 volumio go-librespot[29042]: time="2026-02-12T18:23:10-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 12 18:23:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:10 volumio volumio[28950]: info: Loading plugin "squeezelite_mc"... Feb 12 18:23:11 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 18:23:11 volumio volumio[28950]: info: Loading plugin "youtube2"... Feb 12 18:23:12 volumio volumio[28950]: info: Loading plugin "ytcr"... Feb 12 18:23:13 volumio volumio[28950]: info: Loading plugin "ytmusic"... Feb 12 18:23:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Feb 12 18:23:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:13 volumio go-librespot[29051]: go-librespot daemon starting... Feb 12 18:23:13 volumio go-librespot[29052]: time="2026-02-12T18:23:13-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:13 volumio go-librespot[29052]: time="2026-02-12T18:23:13-06:00" level=debug msg="app state loaded" Feb 12 18:23:13 volumio go-librespot[29052]: time="2026-02-12T18:23:13-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:13 volumio go-librespot[29052]: time="2026-02-12T18:23:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:14 volumio volumio[28950]: info: Plugin now_playing is not enabled Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "outputs"... Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "albumart"... Feb 12 18:23:14 volumio volumio[28950]: info: Plugin example_plugin is not enabled Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "inputs"... Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "updater_comm"... Feb 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-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 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-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 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-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 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-06:00" level=info msg="zeroconf server listening on port 37369" Feb 12 18:23:14 volumio volumio[28950]: info: Plugin mpdemulation is not enabled Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "rest_api"... Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "websocket"... Feb 12 18:23:14 volumio volumio[28950]: info: Starting Socket.io Server version 1.7.4 Feb 12 18:23:14 volumio volumio[28950]: info: Plugin fusiondsp is not enabled Feb 12 18:23:14 volumio volumio[28950]: info: Plugin mpdoutput is not enabled Feb 12 18:23:14 volumio volumio[28950]: info: Plugin RoonBridge is not enabled Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "podcast"... Feb 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-06:00" level=debug msg="obtained new client token: AADGSwz0UGo0tr7D1BybcGEEK65/oB86KIoRXAbTc7GghNhIwt6GJlP6r9Ejc1g+VxBLGYq63rRqzNuZW8q41Uli60GMS20FPxDXGGwCAmYgy451uF979vHlIEdNmcA1sycVsw2FDoehRCwR6zWZD4tzdveN+0lMpWPPny5vw7ZYiYoEsyDiwNLvhdKmqf8XIT4E4kBrZO+nPlZYlUsd+JAq1lFZF/FqdqLi/KpzHnKrXeodwLw86VU=" Feb 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-06:00" level=debug msg="completed challenge" Feb 12 18:23:14 volumio volumio[28950]: info: ControllerPodcast::constructor Feb 12 18:23:14 volumio go-librespot[29052]: time="2026-02-12T18:23:14-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 12 18:23:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:14 volumio volumio[28950]: info: Loading plugin "volusonic"... Feb 12 18:23:14 volumio volumio[29058]: Forking 3 albumart workers Feb 12 18:23:16 volumio volumio[28950]: info: Applying required configuration parameters for plugin volusonic Feb 12 18:23:16 volumio volumio[28950]: info: Loading plugin "backup_restore"... Feb 12 18:23:16 volumio volumio[29085]: Starting albumart workers Feb 12 18:23:16 volumio volumio[29084]: Starting albumart workers Feb 12 18:23:16 volumio volumio[29083]: Starting albumart workers Feb 12 18:23:16 volumio volumio[28950]: info: Applying required configuration parameters for plugin backup_restore Feb 12 18:23:16 volumio volumio[28950]: info: Plugin rpi_eeprom_config is not enabled Feb 12 18:23:16 volumio volumio[28950]: info: Plugin rpi_eeprom_updater is not enabled Feb 12 18:23:16 volumio volumio[28950]: info: Loading plugin "scheduledrestart"... Feb 12 18:23:16 volumio volumio[28950]: info: Applying required configuration parameters for plugin scheduledrestart Feb 12 18:23:16 volumio volumio[28950]: info: Plugin Bluetoothremote is not enabled Feb 12 18:23:16 volumio volumio[28950]: info: Plugin music_services_shield is not enabled Feb 12 18:23:16 volumio volumio[28950]: info: Loading plugin "Systeminfo"... Feb 12 18:23:17 volumio volumio-remote-updater[2265]: [2026-02-12 18:23:17] [connect] Successful connection Feb 12 18:23:17 volumio volumio[28950]: info: Plugin peppymeterbasic is not enabled Feb 12 18:23:17 volumio volumio[28950]: info: Loading plugin "peppyspectrum"... Feb 12 18:23:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Feb 12 18:23:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:17 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 18:23:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:17 volumio go-librespot[29131]: go-librespot daemon starting... Feb 12 18:23:17 volumio go-librespot[29133]: time="2026-02-12T18:23:17-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:17 volumio go-librespot[29133]: time="2026-02-12T18:23:17-06:00" level=debug msg="app state loaded" Feb 12 18:23:17 volumio go-librespot[29133]: time="2026-02-12T18:23:17-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:17 volumio go-librespot[29133]: time="2026-02-12T18:23:17-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-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 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-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 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-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 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-06:00" level=info msg="zeroconf server listening on port 38397" Feb 12 18:23:18 volumio volumio[28950]: info: Loading i18n strings for locale en Feb 12 18:23:18 volumio volumio[28950]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 12 18:23:18 volumio volumio[28950]: Updating browse sources language Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-06:00" level=debug msg="obtained new client token: AACjFme6X8zV4qu9LiV3FQKMiaaQcBiq3ns19vUs53OzYkjKH2TDr+cKLRpBaTqohhBFUpVaZDyCmlPhc7In82IqcBKTHZ4xBGFJofMIEOwMwhnvL798yUiOWbZ60aRNyYBSpu5R5hwQg0J6y4vwqREj3L9ikxzz8OsK4dwvruS6YRe4/zcXwr7XbGd10ai9Rpso8Et6Bw+35YmcfUHH03Rb+nFox9vfMNPPu1FeEFjomPymStuEBpU=" Feb 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-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 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-06:00" level=debug msg="completed challenge" Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::initPlayerControls Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:23:18 volumio go-librespot[29133]: time="2026-02-12T18:23:18-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 12 18:23:18 volumio volumio[28950]: Express server listening on port 3000 Feb 12 18:23:18 volumio volumio[28950]: [Metrics] WebUI: 16s 957.22ms Feb 12 18:23:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:18 volumio volumio[28950]: info: CoreStateMachine::resetVolumioState Feb 12 18:23:18 volumio volumio[28950]: info: CoreStateMachine::getcurrentVolume Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:18 volumio sudo[29145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 18:23:18 volumio sudo[29145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:18 volumio sudo[29147]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 18:23:18 volumio sudo[29147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:18 volumio sudo[29145]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:18 volumio sudo[29147]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:18 volumio volumio[28950]: info: Volumio Network Manager: Network status updated: 1 Feb 12 18:23:18 volumio volumio[28950]: info: VolumeController:: Volume=100 Mute =false Feb 12 18:23:18 volumio volumio[28950]: info: CoreStateMachine::pushState Feb 12 18:23:18 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::volumioPushState Feb 12 18:23:18 volumio volumio[28950]: info: CoreStateMachine::updateTrackBlock Feb 12 18:23:18 volumio volumio[28950]: info: CorePlayQueue::getTrackBlock Feb 12 18:23:18 volumio volumio[28950]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 18:23:18 volumio volumio-remote-updater[2265]: [2026-02-12 18:23:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770942197 101 Feb 12 18:23:18 volumio volumio[28950]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 18:23:19 volumio volumio[28950]: info: Reloading queue from file Feb 12 18:23:19 volumio volumio[28950]: info: CoreStateMachine::setRepeat null single undefined Feb 12 18:23:19 volumio volumio[28950]: info: CoreStateMachine::pushState Feb 12 18:23:19 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioPushState Feb 12 18:23:19 volumio volumio[28950]: info: CoreStateMachine::setRandom null Feb 12 18:23:19 volumio volumio[28950]: info: CoreStateMachine::pushState Feb 12 18:23:19 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioPushState Feb 12 18:23:19 volumio volumio[28950]: info: Setting Device type: Raspberry PI Feb 12 18:23:19 volumio volumio[28950]: info: VolumeController:: Volume=100 Mute =false Feb 12 18:23:19 volumio volumio[28950]: info: CoreStateMachine::pushState Feb 12 18:23:19 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioPushState Feb 12 18:23:19 volumio volumio[28950]: info: Completed loading Core Plugins Feb 12 18:23:19 volumio volumio[28950]: info: Preparing to generate the ALSA configuration file Feb 12 18:23:19 volumio sudo[29160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 18:23:19 volumio sudo[29160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:19 volumio volumio[28950]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 12 18:23:19 volumio volumio[28950]: info: Discovery: Found device Volumio Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioGetState Feb 12 18:23:19 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:19 volumio volumio[28950]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 12 18:23:19 volumio volumio[28950]: info: Discovery: Found device Volumio Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioGetState Feb 12 18:23:19 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:19 volumio volumio[28950]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 18:23:19 volumio volumio[28950]: info: Reading ALSA contributions from plugins. Feb 12 18:23:19 volumio volumio[28950]: info: Asound.conf file unchanged, so no further update is needed Feb 12 18:23:19 volumio volumio[28950]: info: Output device has changed, restarting MPD Feb 12 18:23:19 volumio sudo[29160]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:19 volumio volumio[28950]: info: Output device has changed, restarting Shairport Sync Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:19 volumio sudo[29163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 18:23:19 volumio sudo[29163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:19 volumio sudo[29165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 18:23:19 volumio sudo[29165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:19 volumio sudo[29163]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:19 volumio volumio[28950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:23:19 volumio volumio[28950]: info: ___________ START PLUGINS ___________ Feb 12 18:23:19 volumio volumio[28950]: info: ControllerMpd::onStart: Initializing MPD Feb 12 18:23:19 volumio volumio[28950]: info: Creating MPD Configuration file Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:19 volumio volumio[28950]: info: [1770942199472] CoreMusicLibrary::Adding element Media Servers Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:19 volumio sudo[29175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 18:23:19 volumio sudo[29173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 18:23:19 volumio sudo[29175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:19 volumio sudo[29173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:19 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 18:23:19 volumio sudo[29173]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:19 volumio volumio[28950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:19 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 18:23:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 18:23:19 volumio systemd[1]: mpd.service: Consumed 4.131s CPU time. Feb 12 18:23:19 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 18:23:19 volumio volumio[28950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:23:19 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:19 volumio volumio[28950]: info: [1770942199684] CoreMusicLibrary::Adding element Last_100 Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:19 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:19 volumio volumio[28950]: info: [1770942199690] CoreMusicLibrary::Adding element Webradio Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 18:23:19 volumio volumio[28950]: info: Initializing BBC Radios Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:19 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:19 volumio volumio[28950]: info: [1770942199763] CoreMusicLibrary::Adding element Bandcamp Discover Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:19 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:19 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:19 volumio volumio[28950]: info: [1770942199778] CoreMusicLibrary::Adding element SoundCloud Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:19 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:19 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:19 volumio volumio[28950]: info: Creating Spotify config file Feb 12 18:23:19 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio sudo[29200]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 18:23:20 volumio sudo[29200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 18:23:20 volumio sudo[29200]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Starting proxy server... Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:20 volumio volumio[28950]: info: [1770942200138] CoreMusicLibrary::Adding element YouTube2 Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:20 volumio volumio[28950]: info: [1770942200157] CoreMusicLibrary::Adding element YouTube Music Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube Music Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:20 volumio volumio[28950]: info: [1770942200168] CoreMusicLibrary::Adding element Podcast Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube Music Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Podcast Feb 12 18:23:20 volumio volumio[28950]: info: Loading i18n strings for locale en Feb 12 18:23:20 volumio volumio[28950]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 12 18:23:20 volumio volumio[28950]: Updating browse sources language Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube Music Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Podcast Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube Music Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Podcast Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:20 volumio volumio[28950]: info: [1770942200226] CoreMusicLibrary::Adding element Volusonic Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source YouTube Music Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Podcast Feb 12 18:23:20 volumio volumio[28950]: Cannot find translation for source Volusonic Feb 12 18:23:20 volumio volumio[28950]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 12 18:23:20 volumio volumio[28950]: info: Volumio Calling Home Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Proxy server started on port 40085 Feb 12 18:23:20 volumio volumio[28950]: info: Preparing to generate the ALSA configuration file Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 18:23:20 volumio volumio[28950]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 18:23:20 volumio volumio[28950]: info: Reading ALSA contributions from plugins. Feb 12 18:23:20 volumio volumio[28950]: info: MPD Permissions set Feb 12 18:23:20 volumio volumio[28950]: info: MPD Permissions set Feb 12 18:23:20 volumio volumio[28950]: info: Upmpdcli Daemon Started Feb 12 18:23:20 volumio volumio[28950]: info: Spotify config file written Feb 12 18:23:20 volumio volumio[28950]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 12 18:23:20 volumio sudo[29210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 18:23:20 volumio sudo[29210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:20 volumio volumio[28950]: info: VolumeController:: Volume=100 Mute =false Feb 12 18:23:20 volumio volumio[28950]: info: CoreStateMachine::pushState Feb 12 18:23:20 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::volumioPushState Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Server discovery started Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Player finder started Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:20 volumio volumio[28950]: info: No need to fix Spotify hosts Feb 12 18:23:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:20 volumio go-librespot[29219]: go-librespot daemon starting... Feb 12 18:23:20 volumio sudo[29210]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:20 volumio go-librespot[29225]: time="2026-02-12T18:23:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:20 volumio go-librespot[29225]: time="2026-02-12T18:23:20-06:00" level=debug msg="app state loaded" Feb 12 18:23:20 volumio go-librespot[29225]: time="2026-02-12T18:23:20-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:20 volumio go-librespot[29225]: time="2026-02-12T18:23:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:20 volumio volumio[28950]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 12 18:23:20 volumio volumio[28950]: info: Volumio called home Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 12 18:23:20 volumio volumio[28950]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 12 18:23:21 volumio volumio[28950]: 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 12 18:23:21 volumio volumio[28950]: info: Starting Shairport Sync Feb 12 18:23:21 volumio volumio[28950]: info: Starting Shairport Sync Feb 12 18:23:21 volumio sudo[29234]: 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 12 18:23:21 volumio sudo[29234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio volumio[28950]: info: Starting Shairport Sync Feb 12 18:23:21 volumio sudo[29234]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio sudo[29237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-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 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-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 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-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 12 18:23:21 volumio sudo[29237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-06:00" level=info msg="zeroconf server listening on port 40319" Feb 12 18:23:21 volumio sudo[29240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:23:21 volumio sudo[29240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio sudo[29242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:23:21 volumio sudo[29242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio volumio[28950]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 12 18:23:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 18:23:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 18:23:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:23:21 volumio systemd[1]: shairport-sync.service: Consumed 2.069s CPU time. Feb 12 18:23:21 volumio volumio[28950]: info: Asound.conf file unchanged, so no further update is needed Feb 12 18:23:21 volumio volumio[28950]: info: Output device has changed, restarting MPD Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-06:00" level=debug msg="obtained new client token: AABohqrMygOH6qvAjvGSPyI93YrjVVoVRrzA3nSexVqUmVISEFrY9n8WR98pAhP3zyxuRdIWk3Ngj2BL8g/B5VVICuJMB54PwBxhT1+ikxifMpIujrk8+V0zT5zSu7Pk6YgByCGozEgNqDCokkCYMZaDM+YahMvePgsx863+x4sJiQt8H95xgOYxS+oFbWKDkyc2q6Yu5VInr0e6rUch9ph36NtlgUGZd30Whj3Ok1cv+9/msvBtXQlb1A==" Feb 12 18:23:21 volumio volumio[28950]: info: Output device has changed, restarting Shairport Sync Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio sudo[29249]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 12 18:23:21 volumio sudo[29249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:21 volumio sudo[29251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 18:23:21 volumio sudo[29251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:23:21 volumio sudo[29251]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:21 volumio sudo[29249]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio sudo[29242]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio sudo[29237]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio volumio[28950]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:23:21 volumio sudo[29240]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio sudo[29253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 18:23:21 volumio sudo[29253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-06:00" level=debug msg="completed challenge" Feb 12 18:23:21 volumio go-librespot[29225]: time="2026-02-12T18:23:21-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 12 18:23:21 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 18:23:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 18:23:21 volumio systemd[1]: mpd.service: Consumed 1.546s CPU time. Feb 12 18:23:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 18:23:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 18:23:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 18:23:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 18:23:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 18:23:21 volumio volumio[28950]: info: MPD Permissions set Feb 12 18:23:21 volumio volumio[28950]: info: Shairport-Sync Started Feb 12 18:23:21 volumio volumio[28950]: Error adding Membership: Error: addMembership EINVAL Feb 12 18:23:21 volumio volumio[28950]: info: Shairport-Sync Started Feb 12 18:23:21 volumio volumio[28950]: info: Shairport-Sync Started Feb 12 18:23:21 volumio volumio[28950]: 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 12 18:23:21 volumio volumio[28950]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 18:23:21 volumio volumio[28950]: SPOTIFY: BQCze__3MSyW_fXdNMnOvGRE5RTAUtgTNuVAy6bNJ2q8DZv2OTHMReHszGQlbRFekUH3jaXHzeADyX2ANPnJZti00TG3DBvfHGcdWcisfrsEGjdgbUI1ladCzUXYn2hX5tHAq3AA5ylBrIFyJCz7QLPTfCJ6rfjnvxSFi_6UZhTf_ZxwVlTOOWVMlmMUTpY464TgFd5ywHCtJPUsvNF56nOn5t49AJ6Cj0wzgxw Feb 12 18:23:21 volumio volumio[28950]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 18:23:21 volumio volumio[28950]: info: New Spotify access token = BQCze__3MSyW_fXdNMnOvGRE5RTAUtgTNuVAy6bNJ2q8DZv2OTHMReHszGQlbRFekUH3jaXHzeADyX2ANPnJZti00TG3DBvfHGcdWcisfrsEGjdgbUI1ladCzUXYn2hX5tHAq3AA5ylBrIFyJCz7QLPTfCJ6rfjnvxSFi_6UZhTf_ZxwVlTOOWVMlmMUTpY464TgFd5ywHCtJPUsvNF56nOn5t49AJ6Cj0wzgxw Feb 12 18:23:21 volumio volumio[28950]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 18:23:21 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 18:23:21 volumio sudo[29283]: 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 12 18:23:21 volumio sudo[29283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio sudo[29283]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:21 volumio sudo[29287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 12 18:23:21 volumio sudo[29287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:21 volumio volumio[28950]: info: CoreCommandRouter::volumioGetState Feb 12 18:23:21 volumio volumio[28950]: info: CorePlayQueue::getTrack 0 Feb 12 18:23:21 volumio sudo[29280]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 18:23:21 volumio systemd[1]: Reloading. Feb 12 18:23:21 volumio sudo[29280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 18:23:21 volumio sudo[29280]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:21 volumio volumio[28950]: info: Starting Shairport Sync Feb 12 18:23:22 volumio sudo[29296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:23:22 volumio sudo[29296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:22 volumio volumio[28950]: 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 12 18:23:22 volumio volumio[28950]: info: Spotify Successfully logged in Feb 12 18:23:22 volumio volumio[28950]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:23:22 volumio volumio[28950]: info: [1770942202089] CoreMusicLibrary::Adding element Spotify Feb 12 18:23:22 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source Bandcamp Discover Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source SoundCloud Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source YouTube2 Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source YouTube Music Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source Podcast Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source Volusonic Feb 12 18:23:22 volumio volumio[28950]: Cannot find translation for source Spotify Feb 12 18:23:22 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:22 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:22 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:23:22 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:23:23 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 12 18:23:23 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 12 18:23:23 volumio sudo[29287]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:23 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:23 volumio sudo[29317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:23 volumio sudo[29317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:23 volumio sudo[29317]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 18:23:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 18:23:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:23:23 volumio systemd[1]: shairport-sync.service: Consumed 1.511s CPU time. Feb 12 18:23:23 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 12 18:23:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:23:23 volumio sudo[29296]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:23 volumio volumio[28950]: info: Shairport-Sync Started Feb 12 18:23:23 volumio sudo[29321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 12 18:23:23 volumio sudo[29321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:23 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 12 18:23:23 volumio volumio[28950]: info: go-librespot daemon successfully initialized Feb 12 18:23:24 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 12 18:23:24 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 12 18:23:24 volumio sudo[29321]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:24 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:24 volumio sudo[29352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:24 volumio sudo[29352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Feb 12 18:23:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:24 volumio go-librespot[29354]: go-librespot daemon starting... Feb 12 18:23:24 volumio go-librespot[29355]: time="2026-02-12T18:23:24-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:24 volumio go-librespot[29355]: time="2026-02-12T18:23:24-06:00" level=debug msg="app state loaded" Feb 12 18:23:24 volumio go-librespot[29355]: time="2026-02-12T18:23:24-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:24 volumio sudo[29352]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:24 volumio go-librespot[29355]: time="2026-02-12T18:23:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:24 volumio volumio[28950]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 12 18:23:24 volumio sudo[29364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 12 18:23:24 volumio sudo[29364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:24 volumio sudo[29364]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:24 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 12 18:23:24 volumio sudo[29367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 12 18:23:24 volumio sudo[29367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:25 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 12 18:23:25 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 12 18:23:25 volumio sudo[29367]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-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 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-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 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-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 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-06:00" level=info msg="zeroconf server listening on port 33959" Feb 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-06:00" level=debug msg="obtained new client token: AAD8p/F80JmkUZqNg3BFWqTqX6FqMQ4haUVto0yfL27K+F2fZdmf8BQM5GpW44mJd8jUVoy/Ih7EuASGKerJ9/H2O8NG/AcfCR3K6DC6kWBF1s6dufVZi5FNasaxjLBch0PvlkOQK6J0LVO4r95N45pj/NdtPHukHPP9f2zF6fSd3eKg/Ew0B3VBeyf0AllZq51P7TAWnU3ggMIHAWlyxII+v1e4oVfFi3OhwrU3ncr0gz4u+7Rl5Gg=" Feb 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-06:00" level=debug msg="completed challenge" Feb 12 18:23:25 volumio go-librespot[29355]: time="2026-02-12T18:23:25-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 12 18:23:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:25 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:25 volumio sudo[29375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:25 volumio sudo[29375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:25 volumio sudo[29375]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:26 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:26 volumio sudo[29378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:26 volumio sudo[29378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:26 volumio sudo[29378]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:26 volumio mpd[29315]: 2026-02-12T18:23:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 18:23:26 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:26 volumio sudo[29381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:26 volumio sudo[29381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:26 volumio sudo[29381]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:26 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 18:23:26 volumio sudo[29165]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:26 volumio sudo[29253]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:26 volumio sudo[29175]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:27 volumio volumio[28950]: info: Initializing connection to go-librespot Websocket Feb 12 18:23:27 volumio volumio[28950]: error: MPD error: The expression evaluated to a falsy value: Feb 12 18:23:27 volumio volumio[28950]: assert.ok(self.idling) Feb 12 18:23:27 volumio volumio[28950]: error: The expression evaluated to a falsy value: Feb 12 18:23:27 volumio volumio[28950]: assert.ok(self.idling) Feb 12 18:23:27 volumio volumio[28950]: error: MPD error: The expression evaluated to a falsy value: Feb 12 18:23:27 volumio volumio[28950]: assert.ok(self.idling) Feb 12 18:23:27 volumio volumio[28950]: error: The expression evaluated to a falsy value: Feb 12 18:23:27 volumio volumio[28950]: assert.ok(self.idling) Feb 12 18:23:27 volumio volumio[28950]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:23:27 volumio volumio[28950]: info: MPD running with PID29315 Feb 12 18:23:27 volumio volumio[28950]: ,establishing connection Feb 12 18:23:27 volumio volumio[28950]: error: updateQueue error: null Feb 12 18:23:27 volumio volumio[28950]: error: updateQueue error: null Feb 12 18:23:27 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:27 volumio sudo[29388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:27 volumio sudo[29388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:27 volumio sudo[29388]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:27 volumio volumio[28950]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:23:28 volumio sudo[29391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:23:28 volumio sudo[29391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:28 volumio sudo[29391]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:28 volumio volumio[28950]: info: Completed starting Core Plugins Feb 12 18:23:28 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:28 volumio volumio[28950]: info: ----- MyVolumio plugins startup ---- Feb 12 18:23:28 volumio volumio[28950]: info: ------------------------------------------- Feb 12 18:23:28 volumio volumio[28950]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 18:23:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Feb 12 18:23:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:28 volumio go-librespot[29393]: go-librespot daemon starting... Feb 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-06:00" level=debug msg="app state loaded" Feb 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-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 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-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 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-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 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-06:00" level=info msg="zeroconf server listening on port 37149" Feb 12 18:23:28 volumio go-librespot[29396]: time="2026-02-12T18:23:28-06:00" level=debug msg="obtained new client token: AAApKVEalhazrnqQ+JCjYFAfrqZgnPw3WmZu7Su+7wusJLb1Fb2WR68ZIRMTuLS2FfASzASVMn6Rla8gGEDnZc2j/g+HiZqzz/779IMRlms7iW4VaoQEv4PM7j99kTcz9dcxjMJdejl7j1XXEv/Wdb3tgSKLtX7yr8rC98dKq4zxYU7jT8Ruw8JED4T2lzm3LzMK8nUfLZ8bN3NH+JZTJsE1FuHiTjY93CPXcBTcyRels38d2th2tfliUg==" Feb 12 18:23:29 volumio go-librespot[29396]: time="2026-02-12T18:23:29-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 12 18:23:29 volumio go-librespot[29396]: time="2026-02-12T18:23:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 12 18:23:29 volumio go-librespot[29396]: time="2026-02-12T18:23:29-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:29 volumio go-librespot[29396]: time="2026-02-12T18:23:29-06:00" level=debug msg="completed challenge" Feb 12 18:23:29 volumio go-librespot[29396]: time="2026-02-12T18:23: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 12 18:23:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:30 volumio sudo[29405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 18:23:30 volumio sudo[29405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:30 volumio sudo[29407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 18:23:30 volumio sudo[29407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:30 volumio sudo[29409]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 18:23:30 volumio sudo[29409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:30 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 12 18:23:30 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 12 18:23:30 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 12 18:23:30 volumio volumio[28950]: info: Initializing connection to go-librespot Websocket Feb 12 18:23:30 volumio volumio[28950]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:23:30 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 12 18:23:30 volumio sudo[29407]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:30 volumio sudo[29409]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:30 volumio mpd_monitor.sh[29413]: MPD Monitor Service: Starting MPD Monitor Service Feb 12 18:23:30 volumio sudo[29405]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:30 volumio volumio[28950]: info: Successfully started MPD Monitor Feb 12 18:23:30 volumio volumio[28950]: info: Successfully started MPD Monitor Feb 12 18:23:30 volumio volumio[28950]: info: Successfully started MPD Monitor Feb 12 18:23:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Feb 12 18:23:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:32 volumio go-librespot[29418]: go-librespot daemon starting... Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=debug msg="app state loaded" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-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 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-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 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=info msg="zeroconf server listening on port 43479" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=debug msg="obtained new client token: AADymwCMRiT2O9GiTZoyztMb2WSIUF0QYeCk1bBymu6SPsYVu5edYIigbfGbFnQmZWaaoz/n6ur6B+mh046gzLSouzcgIqKD+yFmkLU2i6KhejnDB4cn29DkoI7m/S/geox7DWzIgFVS3bU3TR63x9MNKaJKsCuLmBKRXJ0Lp0/9Qa5FgUUX30Yz0CbuPI/kejnfEhuxnJvUxGE5LTBKm+vx1rF25uub6VEiR+1M+0C+rfezxYPuKVx/Cg==" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-06:00" level=debug msg="completed challenge" Feb 12 18:23:32 volumio go-librespot[29419]: time="2026-02-12T18:23:32-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 12 18:23:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:33 volumio volumio[28950]: info: Initializing connection to go-librespot Websocket Feb 12 18:23:33 volumio volumio[28950]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:23:34 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 18:23:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Feb 12 18:23:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:36 volumio go-librespot[29441]: go-librespot daemon starting... Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=debug msg="app state loaded" Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23: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 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23: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 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23: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 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=info msg="zeroconf server listening on port 39159" Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=debug msg="obtained new client token: AACmem3JIG8oJ283c1tQtTWNICueSkn00H5weO1vwWqJ/YaPoVmm6dZ8pO7tGLQXP+wV3N09lI3kLvOLZWvj3kUv/7HC+8EXQy0uQVGvDwPZRNOjFqHUVpG/7jbbcJYQMq9Qjhnfc9rx/Uy2JjXn4+mhkLZVS9AJu1WCg7HWx460UEgG+sJVJIDW1baY1JnY4Lc2C4OZabW8v28dHmMB4aUK2dSNVWut0bDn3NQtsqss0jRjyv05eeRiuw==" Feb 12 18:23:36 volumio go-librespot[29442]: time="2026-02-12T18:23:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin bluetooth to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin multiroom to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin metavolumio to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin cd_controller to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 12 18:23:36 volumio volumio[28950]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 12 18:23:37 volumio go-librespot[29442]: time="2026-02-12T18:23:37-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:37 volumio go-librespot[29442]: time="2026-02-12T18:23:37-06:00" level=debug msg="completed challenge" Feb 12 18:23:37 volumio go-librespot[29442]: time="2026-02-12T18:23: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 12 18:23:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:37 volumio volumio[28950]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 12 18:23:37 volumio volumio[28950]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 12 18:23:37 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:37 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:37 volumio volumio[28950]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 18:23:37 volumio volumio[28950]: info: MyVolumio login type: Token Feb 12 18:23:37 volumio volumio[28950]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 12 18:23:37 volumio volumio[28950]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 12 18:23:38 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 18:23:38 volumio volumio[28950]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 12 18:23:38 volumio volumio[28950]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 12 18:23:38 volumio volumio[28950]: info: Streaming services startup Feb 12 18:23:38 volumio volumio[28950]: info: Starting Streaming Daemon Feb 12 18:23:38 volumio sudo[29452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 18:23:38 volumio sudo[29452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:38 volumio volumio[28950]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 12 18:23:38 volumio sudo[29452]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:38 volumio volumio[28950]: info: Initializing connection to go-librespot Websocket Feb 12 18:23:38 volumio volumio[28950]: error: Cannot start Volumio Streaming Daemon Feb 12 18:23:38 volumio volumio[28950]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 18:23:38 volumio volumio[28950]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 18:23:38 volumio volumio[28950]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:23:39 volumio volumio[28950]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 12 18:23:39 volumio volumio[28950]: info: MyVolumio token set successfully Feb 12 18:23:39 volumio volumio[28950]: info: MYVOLUMIO: Adding device Feb 12 18:23:39 volumio volumio[28950]: info: MYVOLUMIO: Evaluating Server Feb 12 18:23:39 volumio volumio[28950]: info: MyVolumio status changed Feb 12 18:23:39 volumio volumio[28950]: info: Streaming services startup Feb 12 18:23:39 volumio volumio[28950]: info: Starting Streaming Daemon Feb 12 18:23:39 volumio volumio[28950]: info: Removing browser output: myVolumio user plan is not superstar Feb 12 18:23:39 volumio volumio[28950]: info: Removing audio output: Feb 12 18:23:39 volumio volumio[28950]: info: Stoppping Tunnel 1 Feb 12 18:23:39 volumio sudo[29479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 18:23:39 volumio sudo[29479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:39 volumio sudo[29481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 12 18:23:39 volumio sudo[29481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio sudo[29479]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:39 volumio volumio[28950]: error: Cannot start Volumio Streaming Daemon Feb 12 18:23:39 volumio volumio[28950]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 12 18:23:39 volumio volumio[28950]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 12 18:23:39 volumio sudo[29481]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:39 volumio volumio[28950]: info: Remote SSH Stopped Feb 12 18:23:39 volumio volumio[28950]: info: Setting Geolocation for MyVolumio to us1 Feb 12 18:23:39 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:39 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:39 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:40 volumio volumio[28950]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 12 18:23:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Feb 12 18:23:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:40 volumio go-librespot[29484]: go-librespot daemon starting... Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-06:00" level=debug msg="app state loaded" Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-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 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-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 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-06:00" level=info msg="zeroconf server listening on port 43691" Feb 12 18:23:40 volumio volumio[28950]: info: Updating MyVolumio device info Feb 12 18:23:40 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:40 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:40 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:40 volumio go-librespot[29485]: time="2026-02-12T18:23:40-06:00" level=debug msg="obtained new client token: AADSeG/DbBXqtcTyzz3GulJD3oWmVzOHD+zhAnCe8noKWkYLw/qckbhtZ72JGGce60rTWWdyhctw6NPzjEMjOb4wxguiYv7Om62DUDIhLWtDfWGpDqJ+ysskF5AFvOn9jkY3pQncFybeNntupEtJze1XAVqmIqbAnOrrjJP06HbdqMwkBU7f43zSu7MiwlD3EGXawpRhid/9FbrEVwmWUgGCelOSOfgwqWdLeC+aLRp6Uqe4LpycamF+WQ==" Feb 12 18:23:41 volumio go-librespot[29485]: time="2026-02-12T18:23:41-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:41 volumio go-librespot[29485]: time="2026-02-12T18:23:41-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:41 volumio go-librespot[29485]: time="2026-02-12T18:23:41-06:00" level=debug msg="completed challenge" Feb 12 18:23:41 volumio go-librespot[29485]: time="2026-02-12T18:23:41-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 12 18:23:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:41 volumio volumio[28950]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 12 18:23:41 volumio volumio[28950]: info: Initializing connection to go-librespot Websocket Feb 12 18:23:41 volumio volumio[28950]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:23:44 volumio volumio[28950]: info: MYVOLUMIO: Adding device Feb 12 18:23:44 volumio volumio[28950]: info: MYVOLUMIO: Evaluating Server Feb 12 18:23:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Feb 12 18:23:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:44 volumio go-librespot[29501]: go-librespot daemon starting... Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="app state loaded" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:44 volumio volumio[28950]: info: Setting Geolocation for MyVolumio to us4 Feb 12 18:23:44 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:44 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:44 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-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 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-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 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=info msg="zeroconf server listening on port 32873" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="obtained new client token: AADjmhVzhrS5ma/pfayPc/S4sC31ymWWb6qNGaPvPcPZNp6QHpe82OWRQQBAiETB/LeTtoSyIsgka9q9pLlKMXkZPgvDojZrG4/+sudfYPkqmfNET6/RIXvg44ErMMwBbrKiQMxmhJ499Q5/+Ni1MtjGL7eAoURLK3SIgZQy6+UhW4b4ows3UWxvN+L/NNwhyb/V4J8XKwjkMN3K1jJoKYsYcihHQpEmiFWg/w5lvSKNboNgEOz4x7Pqzg==" Feb 12 18:23:44 volumio volumio[28950]: info: Initializing connection to go-librespot Websocket Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="new websocket client" Feb 12 18:23:44 volumio volumio[28950]: info: Connection to go-librespot Websocket established Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:44 volumio volumio[28950]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23:44-06:00" level=debug msg="completed challenge" Feb 12 18:23:44 volumio go-librespot[29508]: time="2026-02-12T18:23: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 12 18:23:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:44 volumio volumio[28950]: info: Connection to go-librespot Websocket closed Feb 12 18:23:45 volumio volumio[28950]: info: Updating MyVolumio device info Feb 12 18:23:45 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:45 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:45 volumio volumio[28950]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:23:45 volumio volumio[28950]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 12 18:23:47 volumio volumio[28950]: info: Getting Spotify volume Feb 12 18:23:47 volumio volumio[28950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 18:23:47 volumio volumio[28950]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:23:47 volumio volumio[28950]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 12 18:23:47 volumio volumio[28950]: errno: -111, Feb 12 18:23:47 volumio volumio[28950]: code: 'ECONNREFUSED', Feb 12 18:23:47 volumio volumio[28950]: syscall: 'connect', Feb 12 18:23:47 volumio volumio[28950]: address: '127.0.0.1', Feb 12 18:23:47 volumio volumio[28950]: port: 9879, Feb 12 18:23:47 volumio volumio[28950]: response: undefined Feb 12 18:23:47 volumio volumio[28950]: } Feb 12 18:23:47 volumio volumio[28950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 18:23:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Feb 12 18:23:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:48 volumio go-librespot[29544]: go-librespot daemon starting... Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=debug msg="app state loaded" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-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 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-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 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-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 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=info msg="zeroconf server listening on port 43843" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=debug msg="obtained new client token: AADCnJH+LZjOkKySQ/LlMcr7psHsHvrcdFySNP1o/0BWmwGeiQ7CqQu0xQENWuaxqx6LYrUyPtm4ngJIPyd6wSec1Sc/Q772MBIuaLa81i/FCovX0z3Nje5eNQEOYmO9/nQSZh0Vc+4T0BanRUpBgwkkfZ+EcAQ8R2DWnj08L11Y27h4OH8FnZNpkeVyFVQiZXACP4oXGOBC7NRD5+0bLmLVPtanOeO6XgmO9rTByzeI7ZeXUH12MlIzLg==" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-06:00" level=debug msg="completed challenge" Feb 12 18:23:48 volumio sudo[29554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 18:22' Feb 12 18:23:48 volumio sudo[29554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:48 volumio go-librespot[29545]: time="2026-02-12T18:23:48-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 12 18:23:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:48 volumio sudo[29554]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:48 volumio volumio-remote-updater[2265]: [2026-02-12 18:23:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 12 18:23:48 volumio volumio-remote-updater[2265]: [2026-02-12 18:23:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 12 18:23:48 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:48 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 12 18:23:48 volumio systemd[1]: volumio.service: Consumed 53.231s CPU time. Feb 12 18:23:49 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 18:23:49 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 18:23:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6799. Feb 12 18:23:49 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 12 18:23:49 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 12 18:23:49 volumio systemd[1]: volumio.service: Consumed 53.231s CPU time. Feb 12 18:23:49 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 12 18:23:49 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 12 18:23:51 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:23:51 volumio volumio[29567]: info: ----- Volumio3 ---- Feb 12 18:23:51 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:23:51 volumio volumio[29567]: info: ----- System startup ---- Feb 12 18:23:51 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:23:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Feb 12 18:23:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:51 volumio go-librespot[29591]: go-librespot daemon starting... Feb 12 18:23:51 volumio go-librespot[29592]: time="2026-02-12T18:23:51-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:51 volumio go-librespot[29592]: time="2026-02-12T18:23:51-06:00" level=debug msg="app state loaded" Feb 12 18:23:51 volumio go-librespot[29592]: time="2026-02-12T18:23:51-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:51 volumio go-librespot[29592]: time="2026-02-12T18:23:51-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:52 volumio volumio[29567]: info: MYVOLUMIO Environment detected Feb 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-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 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-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 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-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 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-06:00" level=info msg="zeroconf server listening on port 41065" Feb 12 18:23:52 volumio volumio[29567]: info: Plugin folders cleanup Feb 12 18:23:52 volumio volumio[29567]: info: Scanning into folder /volumio/app/plugins/ Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category audio_interface Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category miscellanea Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category music_service Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category plugins.json Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category system_controller Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category user_interface Feb 12 18:23:52 volumio volumio[29567]: info: Scanning into folder /data/plugins/ Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category audio_interface Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category music_service Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category system_controller Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category system_hardware Feb 12 18:23:52 volumio volumio[29567]: info: Scanning category user_interface Feb 12 18:23:52 volumio volumio[29567]: info: Plugin folders cleanup completed Feb 12 18:23:52 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:23:52 volumio volumio[29567]: info: ----- Core plugins startup ---- Feb 12 18:23:52 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:23:52 volumio volumio[29567]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 18:23:52 volumio volumio[29567]: info: Adding plugin upnp to MyMusic Plugins Feb 12 18:23:52 volumio volumio[29567]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 18:23:52 volumio volumio[29567]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 18:23:52 volumio volumio[29567]: info: Loading plugins from folder /data/plugins/ Feb 12 18:23:52 volumio volumio[29567]: info: Loading plugin "system"... Feb 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-06:00" level=debug msg="obtained new client token: AABE86EfVNmIWJ2bV09YFOFzJVnOVEFWU0asXO9w92Nd2poO1UQr1B5uNhEg0lEDAmQP+Z1j62H542bMNmVfqOZQxo3dx//p0oIRTyvpOGzbs/qFulIBFQuIMLw9tJFsP84boZfbg978NepW/yLjO8uTh2NAvcxaYeqJEouxD4Z10fjnkmrYO0vSHWPp+Wr41OYnLKt6iGQDsxOZ3rsNSEGgibwT9Rn5eIoSRtqYsWzpLo8wzfMXE2M=" Feb 12 18:23:52 volumio volumio[29567]: info: Loading plugin "appearance"... Feb 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-06:00" level=debug msg="completed challenge" Feb 12 18:23:52 volumio go-librespot[29592]: time="2026-02-12T18:23:52-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 12 18:23:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "network"... Feb 12 18:23:53 volumio volumio[29567]: info: Refreshing Cached IP Addresses Feb 12 18:23:53 volumio sudo[29609]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 18:23:53 volumio sudo[29607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 18:23:53 volumio sudo[29607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:53 volumio sudo[29609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "services"... Feb 12 18:23:53 volumio sudo[29609]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "alsa_controller"... Feb 12 18:23:53 volumio sudo[29607]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:53 volumio sudo[29616]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 18:23:53 volumio sudo[29616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:53 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "wizard"... Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "networkfs"... Feb 12 18:23:53 volumio volumio[29567]: info: Starting Udev Watcher for removable devices Feb 12 18:23:53 volumio volumio[29567]: info: Ignoring mount for partition: boot Feb 12 18:23:53 volumio volumio[29567]: info: Ignoring mount for partition: volumio Feb 12 18:23:53 volumio volumio[29567]: info: Ignoring mount for partition: volumio_data Feb 12 18:23:53 volumio volumio[29567]: info: Mounting Device Wikipedia Feb 12 18:23:53 volumio sudo[29646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 12 18:23:53 volumio sudo[29646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:23:53 volumio sudo[29646]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:53 volumio volumio[29567]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 12 18:23:53 volumio volumio[29567]: dmesg(1) may have more information after failed mount system call. Feb 12 18:23:53 volumio volumio[29567]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 12 18:23:53 volumio volumio[29567]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 12 18:23:53 volumio volumio[29567]: dmesg(1) may have more information after failed mount system call. Feb 12 18:23:53 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "volumio_command_line_client"... Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "upnp"... Feb 12 18:23:53 volumio volumio[29567]: info: [1770942233697] Starting Upmpd Daemon Feb 12 18:23:53 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "my_music"... Feb 12 18:23:53 volumio volumio[29567]: info: Loading plugin "mpd"... Feb 12 18:23:53 volumio volumio-remote-updater[2265]: [2026-02-12 18:23:53] [connect] Successful connection Feb 12 18:23:53 volumio sudo[29616]: pam_unix(sudo:session): session closed for user root Feb 12 18:23:54 volumio volumio[29567]: info: Loading plugin "upnp_browser"... Feb 12 18:23:54 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 18:23:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Feb 12 18:23:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:55 volumio go-librespot[29649]: go-librespot daemon starting... Feb 12 18:23:55 volumio go-librespot[29650]: time="2026-02-12T18:23:55-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:55 volumio go-librespot[29650]: time="2026-02-12T18:23:55-06:00" level=debug msg="app state loaded" Feb 12 18:23:55 volumio go-librespot[29650]: time="2026-02-12T18:23:55-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:55 volumio go-librespot[29650]: time="2026-02-12T18:23:55-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-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 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-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 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-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 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-06:00" level=info msg="zeroconf server listening on port 33839" Feb 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-06:00" level=debug msg="obtained new client token: AADYBzaTy2YBC1plj/UJYf7Vo6jwDRO4W8Qv7hObYPyTdqkyqmLXU95UlBIfa9NfFXMB2CXt3UUHtf0Qgiwoqbpe958a2BA6v6hUBo5ojutvvqaMAZ26PsdMb06U8sqZgirtwu4elgb0AwArWrqWD0ic9GcIP4gjdr2Kj+jxZH93d+LVZc9te9jALSc0wOy5yGInkppZjtI+8LeCBJ7cVq2UBxzZNJ3k4cyX409WnTlZhPSdgx3QYlk=" Feb 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-06:00" level=debug msg="completed keyexchange" Feb 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-06:00" level=debug msg="completed challenge" Feb 12 18:23:56 volumio go-librespot[29650]: time="2026-02-12T18:23:56-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 12 18:23:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:23:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "alarm-clock"... Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "airplay_emulation"... Feb 12 18:23:57 volumio volumio[29567]: info: Starting Shairport Sync Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "last_100"... Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "webradio"... Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "i2s_dacs"... Feb 12 18:23:57 volumio volumio[29567]: info: I2S DAC not set, start Auto-detection Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "volumiodiscovery"... Feb 12 18:23:57 volumio volumio[29567]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:57 volumio volumio[29567]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:57 volumio volumio[29567]: *** WARNING *** For more information see Feb 12 18:23:57 volumio volumio[29567]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:57 volumio volumio[29567]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:57 volumio volumio[29567]: *** WARNING *** For more information see Feb 12 18:23:57 volumio node[29567]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:57 volumio node[29567]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:57 volumio node[29567]: *** WARNING *** For more information see Feb 12 18:23:57 volumio node[29567]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 18:23:57 volumio node[29567]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 18:23:57 volumio node[29567]: *** WARNING *** For more information see Feb 12 18:23:57 volumio volumio[29567]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 18:23:57 volumio volumio[29567]: info: Discovery: Started advertising with name: Volumio Feb 12 18:23:57 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 18:23:57 volumio volumio[29567]: info: Loading plugin "bandcamp"... Feb 12 18:23:58 volumio volumio[29567]: info: Plugin calmradio is not enabled Feb 12 18:23:58 volumio volumio[29567]: info: Loading plugin "soundcloud"... Feb 12 18:23:59 volumio volumio[29567]: info: Loading plugin "spop"... Feb 12 18:23:59 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 18:23:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Feb 12 18:23:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:23:59 volumio go-librespot[29660]: go-librespot daemon starting... Feb 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-06:00" level=debug msg="app state loaded" Feb 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-06:00" level=debug msg="stored credentials not found" Feb 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-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 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-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 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-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 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-06:00" level=info msg="zeroconf server listening on port 39253" Feb 12 18:23:59 volumio go-librespot[29661]: time="2026-02-12T18:23:59-06:00" level=debug msg="obtained new client token: AACcw+93lEgZ3fi087pibSbI07+tKiPD5FycfIX0PyOSGBmX8LcB4g3SsdLxn+SADia+AVTIQMnTu350k5UofCfvyef8QP4Pe99Z1w8+xzAruTdSurnrPqazRcjKGDgZHzGwE9vR1ZsdBMS1CwJMcRJDLCa++PP6W011S1ySWkUCR8BO8ElEw3snO+nVEQB6uMmVyRoBnsop0SmECSS4ze6tFVD1Fex3CfPIo1QbLKCGgwaAF6TDwaN3OQ==" Feb 12 18:24:00 volumio go-librespot[29661]: time="2026-02-12T18:24:00-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:24:00 volumio volumio[29567]: info: Loading plugin "squeezelite_mc"... Feb 12 18:24:00 volumio go-librespot[29661]: time="2026-02-12T18:24:00-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:00 volumio go-librespot[29661]: time="2026-02-12T18:24:00-06:00" level=debug msg="completed challenge" Feb 12 18:24:00 volumio go-librespot[29661]: time="2026-02-12T18:24:00-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 12 18:24:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:00 volumio volumio[29567]: info: Loading plugin "youtube2"... Feb 12 18:24:01 volumio volumio[29567]: info: Loading plugin "ytcr"... Feb 12 18:24:02 volumio volumio[29567]: info: Loading plugin "ytmusic"... Feb 12 18:24:03 volumio volumio[29567]: info: Plugin now_playing is not enabled Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "outputs"... Feb 12 18:24:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Feb 12 18:24:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "albumart"... Feb 12 18:24:03 volumio volumio[29567]: info: Plugin example_plugin is not enabled Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "inputs"... Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "updater_comm"... Feb 12 18:24:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:03 volumio go-librespot[29668]: go-librespot daemon starting... Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=debug msg="app state loaded" Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:03 volumio volumio[29567]: info: Plugin mpdemulation is not enabled Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "rest_api"... Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "websocket"... Feb 12 18:24:03 volumio volumio[29567]: info: Starting Socket.io Server version 1.7.4 Feb 12 18:24:03 volumio volumio[29567]: info: Plugin fusiondsp is not enabled Feb 12 18:24:03 volumio volumio[29567]: info: Plugin mpdoutput is not enabled Feb 12 18:24:03 volumio volumio[29567]: info: Plugin RoonBridge is not enabled Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "podcast"... Feb 12 18:24:03 volumio volumio[29567]: info: ControllerPodcast::constructor Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-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 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-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 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-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 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=info msg="zeroconf server listening on port 35991" Feb 12 18:24:03 volumio volumio[29567]: info: Loading plugin "volusonic"... Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=debug msg="obtained new client token: AADcd682RWXjK4CwFSz9YAse63UKXMsebUZASPNX6bdKeCVgZejuyChZR3BaxwHMCsJ8soU3/VYChJJ49YPbNA6QmLWYT+xjll3hGh7ZrAuqGTyd4F/1DN8r/SBxSL4hG+SnyWGqgzCRhvy+7damiC+DmtGYg+gGNwvN00NyKTw4ELndWP990VF9KnZHKFOEafO9LMA9Y0OPtfI2IlHLVp0fSYuCP6QGZ3sl3bnI5Df/v8gr+mpdSJuZKg==" Feb 12 18:24:03 volumio volumio[29669]: Forking 3 albumart workers Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:03 volumio go-librespot[29678]: time="2026-02-12T18:24:03-06:00" level=debug msg="completed challenge" Feb 12 18:24:04 volumio go-librespot[29678]: time="2026-02-12T18:24:04-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 12 18:24:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:05 volumio volumio[29701]: Starting albumart workers Feb 12 18:24:05 volumio volumio[29567]: info: Applying required configuration parameters for plugin volusonic Feb 12 18:24:05 volumio volumio[29702]: Starting albumart workers Feb 12 18:24:05 volumio volumio[29567]: info: Loading plugin "backup_restore"... Feb 12 18:24:05 volumio volumio[29700]: Starting albumart workers Feb 12 18:24:05 volumio volumio[29567]: info: Applying required configuration parameters for plugin backup_restore Feb 12 18:24:05 volumio volumio[29567]: info: Plugin rpi_eeprom_config is not enabled Feb 12 18:24:05 volumio volumio[29567]: info: Plugin rpi_eeprom_updater is not enabled Feb 12 18:24:05 volumio volumio[29567]: info: Loading plugin "scheduledrestart"... Feb 12 18:24:06 volumio volumio[29567]: info: Applying required configuration parameters for plugin scheduledrestart Feb 12 18:24:06 volumio volumio[29567]: info: Plugin Bluetoothremote is not enabled Feb 12 18:24:06 volumio volumio[29567]: info: Plugin music_services_shield is not enabled Feb 12 18:24:06 volumio volumio[29567]: info: Loading plugin "Systeminfo"... Feb 12 18:24:06 volumio volumio-remote-updater[2265]: [2026-02-12 18:24:06] [connect] Successful connection Feb 12 18:24:06 volumio volumio[29567]: info: Plugin peppymeterbasic is not enabled Feb 12 18:24:06 volumio volumio[29567]: info: Loading plugin "peppyspectrum"... Feb 12 18:24:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Feb 12 18:24:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:07 volumio go-librespot[29748]: go-librespot daemon starting... Feb 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-06:00" level=debug msg="app state loaded" Feb 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:07 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 18:24:07 volumio volumio[29567]: info: Loading i18n strings for locale en Feb 12 18:24:07 volumio volumio[29567]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 12 18:24:07 volumio volumio[29567]: Updating browse sources language Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-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 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-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 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-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 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-06:00" level=info msg="zeroconf server listening on port 44825" Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::initPlayerControls Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:24:07 volumio volumio[29567]: Express server listening on port 3000 Feb 12 18:24:07 volumio volumio[29567]: [Metrics] WebUI: 17s 152.08ms Feb 12 18:24:07 volumio volumio[29567]: info: CoreStateMachine::resetVolumioState Feb 12 18:24:07 volumio volumio[29567]: info: CoreStateMachine::getcurrentVolume Feb 12 18:24:07 volumio volumio[29567]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 18:24:07 volumio go-librespot[29749]: time="2026-02-12T18:24:07-06:00" level=debug msg="obtained new client token: AACee+PiwAwahAaqw9ONYf2SrV3+OF2K+kEkpHwNMnayVDXmDxwa+mEXEDWAm3ia9bXiH6Hytlerl4oGd/PpkDwcTAnLL5qPmlj4mo8FTuFsWTN7rsGVw+9HvtLOElKrZo6e1e67kvE3XgmWMYuJeCmoTlEgiH0z1oaVCRt8eqXZ8pvQWopuSE61jCIGGsqCCGy+kd9u7puw6SgX7GrqQkFm890pC8PvSIVJamGaW7WR4UNfUMon1WLOJA==" Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:08 volumio go-librespot[29749]: time="2026-02-12T18:24:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:24:08 volumio sudo[29762]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 18:24:08 volumio sudo[29762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio sudo[29762]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:08 volumio sudo[29764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 18:24:08 volumio sudo[29764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio go-librespot[29749]: time="2026-02-12T18:24:08-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:08 volumio sudo[29764]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:08 volumio go-librespot[29749]: time="2026-02-12T18:24:08-06:00" level=debug msg="completed challenge" Feb 12 18:24:08 volumio volumio[29567]: info: Volumio Network Manager: Network status updated: 1 Feb 12 18:24:08 volumio go-librespot[29749]: time="2026-02-12T18:24:08-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 12 18:24:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:08 volumio volumio-remote-updater[2265]: [2026-02-12 18:24:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770942246 101 Feb 12 18:24:08 volumio volumio[29567]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 12 18:24:08 volumio volumio[29567]: info: VolumeController:: Volume=100 Mute =false Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::pushState Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioPushState Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::updateTrackBlock Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrackBlock Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 18:24:08 volumio volumio[29567]: info: Reloading queue from file Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::setRepeat null single undefined Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::pushState Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioPushState Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::setRandom null Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::pushState Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioPushState Feb 12 18:24:08 volumio volumio[29567]: info: Setting Device type: Raspberry PI Feb 12 18:24:08 volumio volumio[29567]: info: VolumeController:: Volume=100 Mute =false Feb 12 18:24:08 volumio volumio[29567]: info: CoreStateMachine::pushState Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioPushState Feb 12 18:24:08 volumio sudo[29778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 18:24:08 volumio volumio[29567]: info: Completed loading Core Plugins Feb 12 18:24:08 volumio sudo[29778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio volumio[29567]: info: Preparing to generate the ALSA configuration file Feb 12 18:24:08 volumio volumio[29567]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 12 18:24:08 volumio volumio[29567]: info: Discovery: Found device Volumio Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioGetState Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:08 volumio volumio[29567]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 12 18:24:08 volumio volumio[29567]: info: Discovery: Found device Volumio Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioGetState Feb 12 18:24:08 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:08 volumio sudo[29778]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:08 volumio volumio[29567]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 18:24:08 volumio volumio[29567]: info: Reading ALSA contributions from plugins. Feb 12 18:24:08 volumio volumio[29567]: info: Upmpdcli Daemon Started Feb 12 18:24:08 volumio volumio[29567]: info: Asound.conf file unchanged, so no further update is needed Feb 12 18:24:08 volumio volumio[29567]: info: Output device has changed, restarting MPD Feb 12 18:24:08 volumio volumio[29567]: info: Output device has changed, restarting Shairport Sync Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:08 volumio sudo[29781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 18:24:08 volumio sudo[29781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio sudo[29781]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:08 volumio sudo[29783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 18:24:08 volumio sudo[29783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio volumio[29567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:24:08 volumio volumio[29567]: info: ___________ START PLUGINS ___________ Feb 12 18:24:08 volumio volumio[29567]: info: ControllerMpd::onStart: Initializing MPD Feb 12 18:24:08 volumio volumio[29567]: info: Creating MPD Configuration file Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:08 volumio volumio[29567]: info: [1770942248938] CoreMusicLibrary::Adding element Media Servers Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:08 volumio sudo[29791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 18:24:08 volumio sudo[29791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 18:24:08 volumio sudo[29793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 18:24:08 volumio sudo[29791]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:08 volumio sudo[29793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:08 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:09 volumio volumio[29567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:09 volumio volumio[29567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249068] CoreMusicLibrary::Adding element Last_100 Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249072] CoreMusicLibrary::Adding element Webradio Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 18:24:09 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 18:24:09 volumio systemd[1]: mpd.service: Consumed 4.041s CPU time. Feb 12 18:24:09 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 18:24:09 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 18:24:09 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 18:24:09 volumio volumio[29567]: info: Initializing BBC Radios Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249148] CoreMusicLibrary::Adding element Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249159] CoreMusicLibrary::Adding element SoundCloud Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 18:24:09 volumio volumio[29567]: info: Creating Spotify config file Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:09 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 18:24:09 volumio sudo[29818]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 18:24:09 volumio sudo[29818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 18:24:09 volumio sudo[29818]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:09 volumio volumio[29567]: info: [squeezelite_mc] Starting proxy server... Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249526] CoreMusicLibrary::Adding element YouTube2 Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249545] CoreMusicLibrary::Adding element YouTube Music Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube Music Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249556] CoreMusicLibrary::Adding element Podcast Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube Music Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Podcast Feb 12 18:24:09 volumio volumio[29567]: info: Loading i18n strings for locale en Feb 12 18:24:09 volumio volumio[29567]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 12 18:24:09 volumio volumio[29567]: Updating browse sources language Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube Music Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Podcast Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube Music Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Podcast Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:09 volumio volumio[29567]: info: [1770942249603] CoreMusicLibrary::Adding element Volusonic Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source YouTube Music Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Podcast Feb 12 18:24:09 volumio volumio[29567]: Cannot find translation for source Volusonic Feb 12 18:24:09 volumio volumio[29567]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 12 18:24:09 volumio volumio[29567]: info: Volumio Calling Home Feb 12 18:24:09 volumio volumio[29567]: info: [squeezelite_mc] Proxy server started on port 36537 Feb 12 18:24:09 volumio volumio[29567]: info: Preparing to generate the ALSA configuration file Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 18:24:09 volumio volumio[29567]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 18:24:09 volumio volumio[29567]: info: Reading ALSA contributions from plugins. Feb 12 18:24:09 volumio volumio[29567]: info: MPD Permissions set Feb 12 18:24:09 volumio volumio[29567]: info: MPD Permissions set Feb 12 18:24:09 volumio volumio[29567]: info: Spotify config file written Feb 12 18:24:09 volumio sudo[29828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 12 18:24:09 volumio sudo[29828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:09 volumio volumio[29567]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 12 18:24:09 volumio volumio[29567]: info: VolumeController:: Volume=100 Mute =false Feb 12 18:24:09 volumio volumio[29567]: info: CoreStateMachine::pushState Feb 12 18:24:09 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:09 volumio volumio[29567]: info: CoreCommandRouter::volumioPushState Feb 12 18:24:09 volumio volumio[29567]: info: [squeezelite_mc] Server discovery started Feb 12 18:24:09 volumio volumio[29567]: info: [squeezelite_mc] Player finder started Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 12 18:24:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio volumio[29567]: info: No need to fix Spotify hosts Feb 12 18:24:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:10 volumio go-librespot[29832]: go-librespot daemon starting... Feb 12 18:24:10 volumio sudo[29828]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=debug msg="app state loaded" Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:10 volumio volumio[29567]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 12 18:24:10 volumio volumio[29567]: info: Volumio called home Feb 12 18:24:10 volumio volumio[29567]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 12 18:24:10 volumio volumio[29567]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 12 18:24:10 volumio volumio[29567]: 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 12 18:24:10 volumio volumio[29567]: info: Starting Shairport Sync Feb 12 18:24:10 volumio volumio[29567]: info: Starting Shairport Sync Feb 12 18:24:10 volumio volumio[29567]: info: Starting Shairport Sync Feb 12 18:24:10 volumio sudo[29855]: 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 12 18:24:10 volumio sudo[29855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio sudo[29855]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-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 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-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 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-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 12 18:24:10 volumio sudo[29858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=info msg="zeroconf server listening on port 46615" Feb 12 18:24:10 volumio sudo[29860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:24:10 volumio sudo[29858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio sudo[29860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio volumio[29567]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 12 18:24:10 volumio sudo[29863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:24:10 volumio volumio[29567]: info: Asound.conf file unchanged, so no further update is needed Feb 12 18:24:10 volumio volumio[29567]: info: Output device has changed, restarting MPD Feb 12 18:24:10 volumio sudo[29863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 18:24:10 volumio volumio[29567]: info: Output device has changed, restarting Shairport Sync Feb 12 18:24:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:24:10 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:10 volumio systemd[1]: shairport-sync.service: Consumed 2.102s CPU time. Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=debug msg="obtained new client token: AABQx0su9xO3CQyR08ul0QixujWZj8kD5uK1QXIM7orkEcZcak27h+b0ZBjtAcHXT7V4TXhiYbHCrlyKVIXG9C0BsL16CMXbzdTfwP+pSYtE8QEDRbJv/oGduI1kqs/blRnoES/EsB/j8ef4O0+B4wi1ISoIdQUNlXQ8Se2s9b6kPvwdeA8SvgTNIuCUomvdd57QaUaODAQmyZ/PoN8gVDDb4L0BOYHDOkXe8JyBu7gPsENkxyxLlxT8Lg==" Feb 12 18:24:10 volumio sudo[29868]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 12 18:24:10 volumio sudo[29868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio volumio[29567]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 18:24:10 volumio sudo[29870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 18:24:10 volumio sudo[29870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio sudo[29873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 18:24:10 volumio sudo[29873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:24:10 volumio sudo[29868]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio sudo[29870]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-06:00" level=debug msg="completed challenge" Feb 12 18:24:10 volumio sudo[29858]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio sudo[29860]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio go-librespot[29843]: time="2026-02-12T18:24:10-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 12 18:24:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:10 volumio volumio[29567]: info: MPD Permissions set Feb 12 18:24:10 volumio volumio[29567]: 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 12 18:24:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 18:24:10 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 18:24:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 18:24:10 volumio systemd[1]: mpd.service: Consumed 1.506s CPU time. Feb 12 18:24:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 18:24:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:24:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 18:24:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 18:24:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 18:24:10 volumio mpd_monitor.sh[29850]: MPD error: Connection reset by peer Feb 12 18:24:10 volumio mpd_monitor.sh[29413]: MPD Monitor Service: MPD Appears to be inactive, restarting Feb 12 18:24:10 volumio mpd_monitor.sh[29903]: mpd: no process found Feb 12 18:24:10 volumio volumio[29567]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 18:24:10 volumio volumio[29567]: SPOTIFY: BQB3Nn9qvEWOcMPGKc_cVkpvmwrr-XUbjOr87o40_sE70j2S-PLt5pG5HTgc0hm3AgniKKKDOvaK4vJODQBxH3nq6LaUE26z-_MPCewBml95FBN2ur2nxxwUxxXWFEbZXwZYC7fW0uhb8f_sU6PifjYgEkp2JfwnkCJ2xLlb1S6ERdrlhhBPLvzcTDlGvK6emtO3bfwzC14KP_Rt5uBvv2KRuJzipmyg9gQX8dc Feb 12 18:24:10 volumio volumio[29567]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 12 18:24:10 volumio volumio[29567]: info: New Spotify access token = BQB3Nn9qvEWOcMPGKc_cVkpvmwrr-XUbjOr87o40_sE70j2S-PLt5pG5HTgc0hm3AgniKKKDOvaK4vJODQBxH3nq6LaUE26z-_MPCewBml95FBN2ur2nxxwUxxXWFEbZXwZYC7fW0uhb8f_sU6PifjYgEkp2JfwnkCJ2xLlb1S6ERdrlhhBPLvzcTDlGvK6emtO3bfwzC14KP_Rt5uBvv2KRuJzipmyg9gQX8dc Feb 12 18:24:10 volumio volumio[29567]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 12 18:24:10 volumio sudo[29901]: 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 12 18:24:10 volumio sudo[29901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:10 volumio sudo[29901]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 18:24:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 18:24:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:24:11 volumio sudo[29863]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:11 volumio volumio[29567]: info: Shairport-Sync Started Feb 12 18:24:11 volumio volumio[29567]: Error adding Membership: Error: addMembership EINVAL Feb 12 18:24:11 volumio volumio[29567]: info: Shairport-Sync Started Feb 12 18:24:11 volumio volumio[29567]: info: Shairport-Sync Started Feb 12 18:24:11 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 18:24:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 18:24:11 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 12 18:24:11 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 18:24:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 18:24:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 18:24:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 18:24:11 volumio sudo[29910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 12 18:24:11 volumio sudo[29910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:11 volumio systemd[1]: Reloading. Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::volumioGetState Feb 12 18:24:11 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:11 volumio volumio[29567]: info: Starting Shairport Sync Feb 12 18:24:11 volumio sudo[29934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 18:24:11 volumio sudo[29934]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:11 volumio sudo[29926]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 18:24:11 volumio sudo[29926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 18:24:11 volumio sudo[29926]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:11 volumio volumio[29567]: 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 12 18:24:11 volumio volumio[29567]: info: Spotify Successfully logged in Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 18:24:11 volumio volumio[29567]: info: [1770942251737] CoreMusicLibrary::Adding element Spotify Feb 12 18:24:11 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source Bandcamp Discover Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source SoundCloud Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source YouTube2 Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source YouTube Music Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source Podcast Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source Volusonic Feb 12 18:24:11 volumio volumio[29567]: Cannot find translation for source Spotify Feb 12 18:24:12 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:12 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:12 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 18:24:12 volumio volumio[29567]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 18:24:13 volumio nmbd[1061]: [2026/02/12 18:24:13.003331, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 12 18:24:13 volumio nmbd[1061]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.221 for name WORKGROUP<1d>. Feb 12 18:24:13 volumio nmbd[1061]: This response was from IP 169.254.249.234, reporting an IP address of 192.168.1.112. Feb 12 18:24:13 volumio nmbd[1061]: [2026/02/12 18:24:13.005319, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 12 18:24:13 volumio nmbd[1061]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.221 for name WORKGROUP<1d>. Feb 12 18:24:13 volumio nmbd[1061]: This response was from IP 169.254.249.234, reporting an IP address of 192.168.1.112. Feb 12 18:24:13 volumio volumio[29567]: info: go-librespot daemon successfully initialized Feb 12 18:24:13 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 12 18:24:13 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 12 18:24:14 volumio sudo[29910]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:14 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:14 volumio sudo[29970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:14 volumio sudo[29970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Feb 12 18:24:14 volumio sudo[29970]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:14 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 12 18:24:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:14 volumio sudo[29973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 12 18:24:14 volumio sudo[29973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:14 volumio go-librespot[29974]: go-librespot daemon starting... Feb 12 18:24:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 18:24:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 18:24:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:24:14 volumio systemd[1]: shairport-sync.service: Consumed 1.461s CPU time. Feb 12 18:24:14 volumio go-librespot[29976]: time="2026-02-12T18:24:14-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:14 volumio go-librespot[29976]: time="2026-02-12T18:24:14-06:00" level=debug msg="app state loaded" Feb 12 18:24:14 volumio go-librespot[29976]: time="2026-02-12T18:24:14-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:14 volumio go-librespot[29976]: time="2026-02-12T18:24:14-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 18:24:14 volumio sudo[29934]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:14 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 12 18:24:14 volumio volumio[29567]: info: Shairport-Sync Started Feb 12 18:24:15 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 12 18:24:15 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 12 18:24:15 volumio sudo[29973]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-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 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-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 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-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 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-06:00" level=info msg="zeroconf server listening on port 45607" Feb 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-06:00" level=debug msg="obtained new client token: AAAWuW958VpJUdcRyTP8vJlCS3nKvlIBH7qOcQWWzLDeMaBuWTn9cIAsa7eOEa7xWZ4Wbe6G35RncAznVgrXsh823f7aZUF35K0ZI4vcfplohF4oo95DPzNn9ODatWF0d60JbCOgxH8LxIdxLoWbmFvpV+LbRg7cTQpsKohRQq+BX7H3zURmwsVJFaWnvUZV7S5l84aD1OvCjQYZiLhiw1j+vljpDVmq08iEZfCc4X8wK+JYCyzPMFtF7g==" Feb 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-06:00" level=debug msg="completed challenge" Feb 12 18:24:15 volumio go-librespot[29976]: time="2026-02-12T18:24:15-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 12 18:24:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:15 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:15 volumio sudo[29999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:15 volumio sudo[29999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:15 volumio sudo[29999]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:15 volumio volumio[29567]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 12 18:24:15 volumio sudo[30003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 12 18:24:15 volumio sudo[30003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:15 volumio sudo[30003]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:15 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 12 18:24:15 volumio sudo[30006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 12 18:24:15 volumio sudo[30006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:15 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 12 18:24:15 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 12 18:24:15 volumio sudo[30006]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:16 volumio volumio[29567]: info: Initializing connection to go-librespot Websocket Feb 12 18:24:16 volumio volumio[29567]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:24:16 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:16 volumio sudo[30013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:16 volumio sudo[30013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:16 volumio sudo[30013]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:17 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:17 volumio sudo[30016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:17 volumio sudo[30016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:17 volumio sudo[30016]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:17 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:17 volumio sudo[30019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:17 volumio sudo[30019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:17 volumio sudo[30019]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:17 volumio mpd[29968]: 2026-02-12T18:24:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 18:24:17 volumio kernel: hwmon hwmon1: Voltage normalised Feb 12 18:24:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 18:24:17 volumio mpd_monitor.sh[29413]: MPD Monitor Service: MPD restarted due to no mpc output. Feb 12 18:24:17 volumio sudo[29873]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:17 volumio sudo[29793]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:17 volumio sudo[29783]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:18 volumio volumio[29567]: error: MPD error: The expression evaluated to a falsy value: Feb 12 18:24:18 volumio volumio[29567]: assert.ok(self.idling) Feb 12 18:24:18 volumio volumio[29567]: error: The expression evaluated to a falsy value: Feb 12 18:24:18 volumio volumio[29567]: assert.ok(self.idling) Feb 12 18:24:18 volumio volumio[29567]: error: MPD error: The expression evaluated to a falsy value: Feb 12 18:24:18 volumio volumio[29567]: assert.ok(self.idling) Feb 12 18:24:18 volumio volumio[29567]: error: The expression evaluated to a falsy value: Feb 12 18:24:18 volumio volumio[29567]: assert.ok(self.idling) Feb 12 18:24:18 volumio volumio[29567]: info: MPD running with PID29968 Feb 12 18:24:18 volumio volumio[29567]: ,establishing connection Feb 12 18:24:18 volumio volumio[29567]: error: updateQueue error: null Feb 12 18:24:18 volumio volumio[29567]: error: updateQueue error: null Feb 12 18:24:18 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:18 volumio sudo[30028]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:18 volumio sudo[30028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:18 volumio sudo[30028]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 12 18:24:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:18 volumio go-librespot[30031]: go-librespot daemon starting... Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-06:00" level=debug msg="app state loaded" Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-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 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-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 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-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 12 18:24:18 volumio volumio[29567]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-06:00" level=info msg="zeroconf server listening on port 39493" Feb 12 18:24:18 volumio sudo[30040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 12 18:24:18 volumio sudo[30040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:18 volumio sudo[30040]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:18 volumio volumio[29567]: info: Completed starting Core Plugins Feb 12 18:24:18 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:24:18 volumio volumio[29567]: info: ----- MyVolumio plugins startup ---- Feb 12 18:24:18 volumio volumio[29567]: info: ------------------------------------------- Feb 12 18:24:18 volumio volumio[29567]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 12 18:24:18 volumio go-librespot[30032]: time="2026-02-12T18:24:18-06:00" level=debug msg="obtained new client token: AABZLwlSSoTixIAfdWQp1AIpnGWovUVONR7IAhi2mmXPsxk55zhh6YbEJgyallFzwso2QKZw2DCvU25bPiwlYbz1PyLrtyBGypLFREDxIZ5XmFN375SIlUe5qwuR69WRPfBlk19RKASccVvEdVNxcznKy2gW9LHbuTJD1QlWv5iEni795rzhNwlgKjAXKToVEfDsh6H4Ocyb9XHkAXnhsyFBOdkcGQe2/v8056WMy8vIkwB6N7jnbIsLlQ==" Feb 12 18:24:19 volumio go-librespot[30032]: time="2026-02-12T18:24:19-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 12 18:24:19 volumio go-librespot[30032]: time="2026-02-12T18:24:19-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 12 18:24:19 volumio go-librespot[30032]: time="2026-02-12T18:24:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Feb 12 18:24:19 volumio go-librespot[30032]: time="2026-02-12T18:24:19-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:19 volumio go-librespot[30032]: time="2026-02-12T18:24:19-06:00" level=debug msg="completed challenge" Feb 12 18:24:19 volumio go-librespot[30032]: time="2026-02-12T18:24:19-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 12 18:24:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:19 volumio volumio[29567]: info: Initializing connection to go-librespot Websocket Feb 12 18:24:19 volumio volumio[29567]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 12 18:24:21 volumio sudo[30043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 18:24:21 volumio sudo[30043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:21 volumio sudo[30045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 18:24:21 volumio sudo[30045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:21 volumio sudo[30047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 12 18:24:21 volumio sudo[30047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 18:24:21 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 12 18:24:21 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 12 18:24:21 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 12 18:24:21 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 12 18:24:21 volumio sudo[30047]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:21 volumio sudo[30045]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:21 volumio mpd_monitor.sh[30051]: MPD Monitor Service: Starting MPD Monitor Service Feb 12 18:24:21 volumio sudo[30043]: pam_unix(sudo:session): session closed for user root Feb 12 18:24:21 volumio volumio[29567]: info: Successfully started MPD Monitor Feb 12 18:24:21 volumio volumio[29567]: info: Successfully started MPD Monitor Feb 12 18:24:21 volumio volumio[29567]: info: Successfully started MPD Monitor Feb 12 18:24:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 12 18:24:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:22 volumio go-librespot[30055]: go-librespot daemon starting... Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="app state loaded" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:22 volumio volumio[29567]: info: Initializing connection to go-librespot Websocket Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="new websocket client" Feb 12 18:24:22 volumio volumio[29567]: info: Connection to go-librespot Websocket established Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-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 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-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 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-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 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=info msg="zeroconf server listening on port 45899" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="obtained new client token: AABYTSxxchnqoTMMzoejOz1OIVhMe30+/4/o7ghzKEQ9iQmIZ6IaUWljMfrJbSrsxv41lrY8XesmC9umnWp2pDy+uwmI8l1Avw4y7vnKRAmWhTse7krHe7M+AHQCuVIN+XDqlu4+aO0Y8jDnbzt4OrbH306tgGPO9ymFZZQ4lshN2LwHz6kJPwdCBIXzkWQ7vBYB+43xS/qEweyDX6pWmLQHc3WD5wJVaserDAM+wrX256bLAhXTNnH25g==" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-06:00" level=debug msg="completed challenge" Feb 12 18:24:22 volumio go-librespot[30056]: time="2026-02-12T18:24:22-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 12 18:24:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:22 volumio volumio[29567]: info: Connection to go-librespot Websocket closed Feb 12 18:24:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 12 18:24:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 12 18:24:26 volumio go-librespot[30077]: go-librespot daemon starting... Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=info msg="running go-librespot 0.4.0" Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="app state loaded" Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="stored credentials not found" Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 12 18:24:26 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 12 18:24:26 volumio volumio[29567]: info: Getting Spotify volume Feb 12 18:24:26 volumio volumio[29567]: info: Initializing connection to go-librespot Websocket Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="new websocket client" Feb 12 18:24:26 volumio volumio[29567]: info: Connection to go-librespot Websocket established Feb 12 18:24:26 volumio volumio[29567]: 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 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-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 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-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 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-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 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=info msg="zeroconf server listening on port 39213" Feb 12 18:24:26 volumio volumio[29567]: info: CoreCommandRouter::volumioGetState Feb 12 18:24:26 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:26 volumio volumio[29567]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 12 18:24:26 volumio volumio[29567]: SPOTIFY: SPOTIFY VOLUME undefined Feb 12 18:24:26 volumio volumio[29567]: SPOTIFY: VOLUMIO VOLUME 100 Feb 12 18:24:26 volumio volumio[29567]: info: Aligning Spotify Volume to Volumio Volume Feb 12 18:24:26 volumio volumio[29567]: info: CoreCommandRouter::volumioGetState Feb 12 18:24:26 volumio volumio[29567]: info: CorePlayQueue::getTrack 0 Feb 12 18:24:26 volumio volumio[29567]: info: Setting Spotify Volume from Volumio: 100 Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="obtained new client token: AAAou26vFIzT+cEbOb835DJGsc/UfDKa7R16k4BYgTRWEP6Na+ArZKW9IhwrkoU/AkuGUdff+9WCaYvR3y7bysuLvIpGB8CkoSfoiq4fJ2yYkEbQOYybHo5bWIziEzcH29xMXbRS5mOv0jKeJna31qkRN+Abls3/i0Fyerz9MRvz3Apj9JjEQyvUsNRp8E+1O4Ch+Zkm8yRYAxNBuK+pzvVGtbxzzmxY229S+rnHHDBziU4KkofeZsG1Nw==" Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-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 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="completed keyexchange" Feb 12 18:24:26 volumio go-librespot[30078]: time="2026-02-12T18:24:26-06:00" level=debug msg="completed challenge" Feb 12 18:24:27 volumio go-librespot[30078]: time="2026-02-12T18:24: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 12 18:24:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 12 18:24:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 12 18:24:27 volumio volumio[29567]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 18:24:27 volumio volumio[29567]: Error: socket hang up Feb 12 18:24:27 volumio volumio[29567]: at connResetException (node:internal/errors:720:14) Feb 12 18:24:27 volumio volumio[29567]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 12 18:24:27 volumio volumio[29567]: at Socket.emit (node:events:526:35) Feb 12 18:24:27 volumio volumio[29567]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 12 18:24:27 volumio volumio[29567]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 12 18:24:27 volumio volumio[29567]: code: 'ECONNRESET', Feb 12 18:24:27 volumio volumio[29567]: response: undefined Feb 12 18:24:27 volumio volumio[29567]: } Feb 12 18:24:27 volumio volumio[29567]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 18:24:28 volumio sudo[30100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 18:23' Feb 12 18:24:28 volumio sudo[30100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="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"