Feb 10 00:43:00 volumio sudo[7354]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:00 volumio volumio[7275]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 10 00:43:00 volumio volumio[7275]: dmesg(1) may have more information after failed mount system call. Feb 10 00:43:00 volumio volumio[7275]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 10 00:43:00 volumio volumio[7275]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 10 00:43:00 volumio volumio[7275]: dmesg(1) may have more information after failed mount system call. Feb 10 00:43:00 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 00:43:00 volumio volumio[7275]: info: Loading plugin "volumio_command_line_client"... Feb 10 00:43:00 volumio volumio[7275]: info: Loading plugin "upnp"... Feb 10 00:43:00 volumio volumio[7275]: info: [1770705780036] Starting Upmpd Daemon Feb 10 00:43:00 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 00:43:00 volumio volumio[7275]: info: Loading plugin "my_music"... Feb 10 00:43:00 volumio volumio[7275]: info: Loading plugin "mpd"... Feb 10 00:43:00 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 10 00:43:00 volumio sudo[7325]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:00 volumio volumio-remote-updater[751]: [2026-02-10 00:43:00] [connect] Successful connection Feb 10 00:43:00 volumio volumio[7275]: info: Loading plugin "upnp_browser"... Feb 10 00:43:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Feb 10 00:43:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:02 volumio go-librespot[7357]: go-librespot daemon starting... Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=debug msg="app state loaded" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43: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 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43: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 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43: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 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=info msg="zeroconf server listening on port 34599" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=debug msg="obtained new client token: AAD/BfLv55re49paNhBjv5DqW6N2JasJqqdcz15i5HAh8v/ni1B7pRLETUmnR+rt/tIaU7QcDE5UvthE+5KKOzFaWHR/8ph/LJycIvkCRzdgbNbWt2qnKWkD/MtCDz793UbIkRG6I5jIXPZXxMoMGkivuvHOkHzVnjXl+y6mNTj0qKs12MEgWxYWiw+Ezq9VkT3L9/i6VgoDP0N1Pm9WJ9Bm1Yijw/rhIwvobrGJRfMlDCK37DGU4vd0kg==" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-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 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43:02-06:00" level=debug msg="completed challenge" Feb 10 00:43:02 volumio go-librespot[7358]: time="2026-02-10T00:43: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 10 00:43:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "alarm-clock"... Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "airplay_emulation"... Feb 10 00:43:03 volumio volumio[7275]: info: Starting Shairport Sync Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "last_100"... Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "webradio"... Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "i2s_dacs"... Feb 10 00:43:03 volumio volumio[7275]: info: I2S DAC not set, start Auto-detection Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "volumiodiscovery"... Feb 10 00:43:03 volumio volumio[7275]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:03 volumio volumio[7275]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:03 volumio volumio[7275]: *** WARNING *** For more information see Feb 10 00:43:03 volumio volumio[7275]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:03 volumio volumio[7275]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:03 volumio volumio[7275]: *** WARNING *** For more information see Feb 10 00:43:03 volumio node[7275]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:03 volumio node[7275]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:03 volumio node[7275]: *** WARNING *** For more information see Feb 10 00:43:03 volumio node[7275]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:03 volumio node[7275]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:03 volumio node[7275]: *** WARNING *** For more information see Feb 10 00:43:03 volumio volumio[7275]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 00:43:03 volumio volumio[7275]: info: Discovery: Started advertising with name: Volumio Feb 10 00:43:03 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 00:43:03 volumio volumio[7275]: info: Loading plugin "bandcamp"... Feb 10 00:43:05 volumio volumio[7275]: info: Plugin calmradio is not enabled Feb 10 00:43:05 volumio volumio[7275]: info: Loading plugin "soundcloud"... Feb 10 00:43:05 volumio volumio[7275]: info: Loading plugin "spop"... Feb 10 00:43:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Feb 10 00:43:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:06 volumio go-librespot[7367]: go-librespot daemon starting... Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=debug msg="app state loaded" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43: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-gew1.spotify.com:80]" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-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 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-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 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=info msg="zeroconf server listening on port 33153" Feb 10 00:43:06 volumio volumio[7275]: info: Loading plugin "squeezelite_mc"... Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=debug msg="obtained new client token: AABFlKcdkgPSaKPO6CfBoXvO/dcD4WQdBLNXP3TOIAEpId7j/9izT5CLgin5S3FgUxNCrZN7APAF+9lYMB5WHgfdOD5svNvUE24HKR7hJyAOd5urKR9flfIEPdf+tDi6wuLVmJa327fjopEv+t6XbO1YnyQcLe/xXBVxl0/xJDYkYOqu6DrY+yJ6oLv0P6OqQSUFPNVvBe4z2XqnOr+L6QjybsKo4ra/S2QOH+n2uQNIhhq9IotMsqEwHg==" Feb 10 00:43:06 volumio kernel: hwmon hwmon1: Voltage normalised Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43:06-06:00" level=debug msg="completed challenge" Feb 10 00:43:06 volumio go-librespot[7368]: time="2026-02-10T00:43: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 10 00:43:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:07 volumio volumio[7275]: info: Loading plugin "youtube2"... Feb 10 00:43:07 volumio volumio[7275]: info: Loading plugin "ytcr"... Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "ytmusic"... Feb 10 00:43:09 volumio volumio[7275]: info: Plugin now_playing is not enabled Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "outputs"... Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "albumart"... Feb 10 00:43:09 volumio volumio[7275]: info: Plugin example_plugin is not enabled Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "inputs"... Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "updater_comm"... Feb 10 00:43:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Feb 10 00:43:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:09 volumio go-librespot[7391]: go-librespot daemon starting... Feb 10 00:43:09 volumio volumio[7275]: info: Plugin mpdemulation is not enabled Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "rest_api"... Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "websocket"... Feb 10 00:43:09 volumio go-librespot[7401]: time="2026-02-10T00:43:09-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:09 volumio go-librespot[7401]: time="2026-02-10T00:43:09-06:00" level=debug msg="app state loaded" Feb 10 00:43:09 volumio go-librespot[7401]: time="2026-02-10T00:43:09-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:09 volumio go-librespot[7401]: time="2026-02-10T00:43:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:09 volumio volumio[7275]: info: Starting Socket.io Server version 1.7.4 Feb 10 00:43:09 volumio volumio[7275]: info: Plugin fusiondsp is not enabled Feb 10 00:43:09 volumio volumio[7275]: info: Plugin mpdoutput is not enabled Feb 10 00:43:09 volumio volumio[7275]: info: Plugin RoonBridge is not enabled Feb 10 00:43:09 volumio volumio[7275]: info: Loading plugin "podcast"... Feb 10 00:43:10 volumio volumio[7275]: info: ControllerPodcast::constructor Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43: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-gew1.spotify.com:80]" Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-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 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-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 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-06:00" level=info msg="zeroconf server listening on port 37741" Feb 10 00:43:10 volumio volumio[7275]: info: Loading plugin "volusonic"... Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-06:00" level=debug msg="obtained new client token: AACwYteNQJzGUt6SZZoV1Ry4VxgiJXhPnDSrrtjdcKsIDHVGZuhOSI+RkIKwCrWwlEFz0JvQEtcfIiNnRgDH9EgQ8KRi2nxRL7CgLHcrJzqcuMB0M9XN9CCwbBThPam6pIuNmjbcQO6m7zcPTkgCzUutyE5Juuywhk3dNZE9Xw8/edswGCsf0p9dQvzF/ZoenwFestvhsKC966ooosw46Jvp4p1GSXJaLI3PCaEeudE58AkVwGuqKLU=" Feb 10 00:43:10 volumio volumio[7390]: Forking 3 albumart workers Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43:10-06:00" level=debug msg="completed challenge" Feb 10 00:43:10 volumio go-librespot[7401]: time="2026-02-10T00:43: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 10 00:43:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:11 volumio volumio[7275]: info: Applying required configuration parameters for plugin volusonic Feb 10 00:43:11 volumio volumio[7275]: info: Loading plugin "backup_restore"... Feb 10 00:43:12 volumio volumio[7411]: Starting albumart workers Feb 10 00:43:12 volumio volumio[7409]: Starting albumart workers Feb 10 00:43:12 volumio volumio[7410]: Starting albumart workers Feb 10 00:43:12 volumio volumio[7275]: info: Applying required configuration parameters for plugin backup_restore Feb 10 00:43:12 volumio volumio[7275]: info: Plugin rpi_eeprom_config is not enabled Feb 10 00:43:12 volumio volumio[7275]: info: Plugin rpi_eeprom_updater is not enabled Feb 10 00:43:12 volumio volumio[7275]: info: Loading plugin "scheduledrestart"... Feb 10 00:43:12 volumio volumio[7275]: info: Applying required configuration parameters for plugin scheduledrestart Feb 10 00:43:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 10 00:43:12 volumio volumio[7275]: info: Plugin Bluetoothremote is not enabled Feb 10 00:43:12 volumio volumio[7275]: info: Plugin music_services_shield is not enabled Feb 10 00:43:12 volumio volumio[7275]: info: Loading plugin "Systeminfo"... Feb 10 00:43:12 volumio volumio-remote-updater[751]: [2026-02-10 00:43:12] [connect] Successful connection Feb 10 00:43:13 volumio volumio[7275]: info: Plugin peppymeterbasic is not enabled Feb 10 00:43:13 volumio volumio[7275]: info: Loading plugin "peppyspectrum"... Feb 10 00:43:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Feb 10 00:43:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:13 volumio go-librespot[7459]: go-librespot daemon starting... Feb 10 00:43:13 volumio go-librespot[7460]: time="2026-02-10T00:43:13-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:13 volumio go-librespot[7460]: time="2026-02-10T00:43:13-06:00" level=debug msg="app state loaded" Feb 10 00:43:13 volumio go-librespot[7460]: time="2026-02-10T00:43:13-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:13 volumio go-librespot[7460]: time="2026-02-10T00:43:13-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-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 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-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 10 00:43:14 volumio volumio[7275]: info: Loading i18n strings for locale en Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-06:00" level=info msg="zeroconf server listening on port 45033" Feb 10 00:43:14 volumio volumio[7275]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 10 00:43:14 volumio volumio[7275]: Updating browse sources language Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-06:00" level=debug msg="obtained new client token: AAA3vcnYlR8YKhEMSkDOaEw0EGW2ZsZLS87zmOIbMRnD2EBYX7qu1dVqC74ZiBsVNOyYJZ1oSZown6Vk7Ms1efxX7JofbUeJNLWABpwwTmdoFAGPabbtCU80H8VCgJJuaGlFVj18W4hpl2du8t9QV/sRTQsNEIDSFsIewpad38H6FKw12cyMSkC4GdF0a+mVX9oUV1R7WX7J942KlZuZQzQ1dBY5PA1jVLcNAqhEkvV4LjUPddNeAeo=" Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::initPlayerControls Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43:14-06:00" level=debug msg="completed challenge" Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:14 volumio volumio[7275]: Express server listening on port 3000 Feb 10 00:43:14 volumio volumio[7275]: [Metrics] WebUI: 17s 440.73ms Feb 10 00:43:14 volumio go-librespot[7460]: time="2026-02-10T00:43: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 10 00:43:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:14 volumio volumio[7275]: info: CoreStateMachine::resetVolumioState Feb 10 00:43:14 volumio volumio[7275]: info: CoreStateMachine::getcurrentVolume Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:14 volumio sudo[7473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 00:43:14 volumio sudo[7473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:14 volumio sudo[7473]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:14 volumio sudo[7475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 00:43:14 volumio sudo[7475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:14 volumio sudo[7475]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:14 volumio volumio[7275]: info: Volumio Network Manager: Network status updated: 1 Feb 10 00:43:14 volumio volumio-remote-updater[751]: [2026-02-10 00:43:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770705792 101 Feb 10 00:43:14 volumio volumio[7275]: 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 10 00:43:14 volumio volumio[7275]: info: VolumeController:: Volume=100 Mute =false Feb 10 00:43:14 volumio volumio[7275]: info: CoreStateMachine::pushState Feb 10 00:43:14 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:14 volumio volumio[7275]: info: CoreStateMachine::updateTrackBlock Feb 10 00:43:14 volumio volumio[7275]: info: CorePlayQueue::getTrackBlock Feb 10 00:43:14 volumio volumio[7275]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 00:43:14 volumio volumio[7275]: info: Reloading queue from file Feb 10 00:43:15 volumio volumio[7275]: info: CoreStateMachine::setRepeat null single undefined Feb 10 00:43:15 volumio volumio[7275]: info: CoreStateMachine::pushState Feb 10 00:43:15 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:15 volumio volumio[7275]: info: CoreStateMachine::setRandom null Feb 10 00:43:15 volumio volumio[7275]: info: CoreStateMachine::pushState Feb 10 00:43:15 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:15 volumio volumio[7275]: info: Setting Device type: Raspberry PI Feb 10 00:43:15 volumio volumio[7275]: info: VolumeController:: Volume=100 Mute =false Feb 10 00:43:15 volumio volumio[7275]: info: CoreStateMachine::pushState Feb 10 00:43:15 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:15 volumio sudo[7488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 00:43:15 volumio sudo[7488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:15 volumio volumio[7275]: info: Completed loading Core Plugins Feb 10 00:43:15 volumio volumio[7275]: info: Preparing to generate the ALSA configuration file Feb 10 00:43:15 volumio volumio[7275]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 10 00:43:15 volumio volumio[7275]: info: Discovery: Found device Volumio Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioGetState Feb 10 00:43:15 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:15 volumio sudo[7488]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:15 volumio volumio[7275]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 10 00:43:15 volumio volumio[7275]: info: Discovery: Found device Volumio Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioGetState Feb 10 00:43:15 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:15 volumio volumio[7275]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 10 00:43:15 volumio volumio[7275]: info: Reading ALSA contributions from plugins. Feb 10 00:43:15 volumio volumio[7275]: info: Upmpdcli Daemon Started Feb 10 00:43:15 volumio volumio[7275]: info: Asound.conf file unchanged, so no further update is needed Feb 10 00:43:15 volumio volumio[7275]: info: Output device has changed, restarting MPD Feb 10 00:43:15 volumio volumio[7275]: info: Output device has changed, restarting Shairport Sync Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:15 volumio sudo[7491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 00:43:15 volumio sudo[7491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:15 volumio sudo[7493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 00:43:15 volumio sudo[7493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:15 volumio sudo[7491]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:15 volumio volumio[7275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:15 volumio volumio[7275]: info: ___________ START PLUGINS ___________ Feb 10 00:43:15 volumio volumio[7275]: info: ControllerMpd::onStart: Initializing MPD Feb 10 00:43:15 volumio volumio[7275]: info: Creating MPD Configuration file Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795321] CoreMusicLibrary::Adding element Media Servers Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 00:43:15 volumio sudo[7503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 00:43:15 volumio sudo[7503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:15 volumio sudo[7501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 00:43:15 volumio sudo[7501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:15 volumio sudo[7501]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:15 volumio volumio[7275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:15 volumio volumio[7275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795442] CoreMusicLibrary::Adding element Last_100 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795447] CoreMusicLibrary::Adding element Webradio Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 00:43:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 00:43:15 volumio systemd[1]: mpd.service: Consumed 5.648s CPU time. Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 00:43:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 00:43:15 volumio volumio[7275]: info: Initializing BBC Radios Feb 10 00:43:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 00:43:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795518] CoreMusicLibrary::Adding element Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795530] CoreMusicLibrary::Adding element SoundCloud Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 00:43:15 volumio volumio[7275]: info: Creating Spotify config file Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:15 volumio sudo[7528]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 00:43:15 volumio sudo[7528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 00:43:15 volumio sudo[7528]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:15 volumio volumio[7275]: info: [squeezelite_mc] Starting proxy server... Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795872] CoreMusicLibrary::Adding element YouTube2 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795893] CoreMusicLibrary::Adding element YouTube Music Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube Music Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795906] CoreMusicLibrary::Adding element Podcast Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube Music Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Podcast Feb 10 00:43:15 volumio volumio[7275]: info: Loading i18n strings for locale en Feb 10 00:43:15 volumio volumio[7275]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 10 00:43:15 volumio volumio[7275]: Updating browse sources language Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube Music Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Podcast Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube Music Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Podcast Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:15 volumio volumio[7275]: info: [1770705795954] CoreMusicLibrary::Adding element Volusonic Feb 10 00:43:15 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source YouTube Music Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Podcast Feb 10 00:43:15 volumio volumio[7275]: Cannot find translation for source Volusonic Feb 10 00:43:15 volumio volumio[7275]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 10 00:43:16 volumio volumio[7275]: info: Volumio Calling Home Feb 10 00:43:16 volumio volumio[7275]: info: [squeezelite_mc] Proxy server started on port 33209 Feb 10 00:43:16 volumio volumio[7275]: info: Preparing to generate the ALSA configuration file Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 00:43:16 volumio volumio[7275]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 10 00:43:16 volumio volumio[7275]: info: Reading ALSA contributions from plugins. Feb 10 00:43:16 volumio volumio[7275]: info: MPD Permissions set Feb 10 00:43:16 volumio volumio[7275]: info: MPD Permissions set Feb 10 00:43:16 volumio volumio[7275]: info: Spotify config file written Feb 10 00:43:16 volumio sudo[7538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 00:43:16 volumio sudo[7538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:16 volumio volumio[7275]: 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 10 00:43:16 volumio volumio[7275]: info: VolumeController:: Volume=100 Mute =false Feb 10 00:43:16 volumio volumio[7275]: info: CoreStateMachine::pushState Feb 10 00:43:16 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:16 volumio volumio[7275]: info: [squeezelite_mc] Server discovery started Feb 10 00:43:16 volumio volumio[7275]: info: [squeezelite_mc] Player finder started Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 10 00:43:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:16 volumio volumio[7275]: info: No need to fix Spotify hosts Feb 10 00:43:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:16 volumio go-librespot[7542]: go-librespot daemon starting... Feb 10 00:43:16 volumio sudo[7538]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="app state loaded" Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:16 volumio volumio[7275]: 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 10 00:43:16 volumio volumio[7275]: info: Volumio called home Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=info msg="zeroconf server listening on port 45951" Feb 10 00:43:16 volumio volumio[7275]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 10 00:43:16 volumio volumio[7275]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 10 00:43:16 volumio volumio[7275]: 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 10 00:43:16 volumio volumio[7275]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 00:43:16 volumio volumio[7275]: SPOTIFY: BQBnXFykzzkEffPRzWOKISh8w0eJwe5d5FuAAJgAkBDijD32T2DxFRNiIByXzyDEE5FZpwkJDO5HvCrcnQg1JcQP9_eUqwfdL09o9RapLPkk2N2dMW0ivyDpENUwVdTdpS9tchkTTL_XuyJEWtEFde5LgiuM_rVxSX-xg_SF8ibpyq3rSzDgYBEUhbngdYCWwrE0X-tueXVe0xL0PqMHrCVIp6Bz0dvw8EbiGTs Feb 10 00:43:16 volumio volumio[7275]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 00:43:16 volumio volumio[7275]: info: New Spotify access token = BQBnXFykzzkEffPRzWOKISh8w0eJwe5d5FuAAJgAkBDijD32T2DxFRNiIByXzyDEE5FZpwkJDO5HvCrcnQg1JcQP9_eUqwfdL09o9RapLPkk2N2dMW0ivyDpENUwVdTdpS9tchkTTL_XuyJEWtEFde5LgiuM_rVxSX-xg_SF8ibpyq3rSzDgYBEUhbngdYCWwrE0X-tueXVe0xL0PqMHrCVIp6Bz0dvw8EbiGTs Feb 10 00:43:16 volumio volumio[7275]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 00:43:16 volumio sudo[7562]: 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 10 00:43:16 volumio sudo[7562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:16 volumio sudo[7562]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:16 volumio volumio[7275]: info: Starting Shairport Sync Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="obtained new client token: AACK70RjehlhxSoRBiy7YVvheespNcnX4WtFOoGL3xusbzWmB8qgxDsaUy1fSby40nIuDjcY4y7pkpkOQllOVcnQv6UBj2t0W6fyjEbyyCiIjktZyVtbAG53X2He7YgeeL3d1oOfSB3kqvqqQR4GQ4gC0ijGnljy6C1k7RsgFmztWZzdRBY4xN4fHotzKZlV6SQo26Lp1bGDlH09BwmTfLzU2Ml0ZLmuH1OWVlRXYNM7z7s9T6g01EcCQA==" Feb 10 00:43:16 volumio volumio[7275]: info: Starting Shairport Sync Feb 10 00:43:16 volumio volumio[7275]: info: Starting Shairport Sync Feb 10 00:43:16 volumio sudo[7566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:16 volumio sudo[7566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:16 volumio sudo[7568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:16 volumio sudo[7568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:16 volumio go-librespot[7553]: time="2026-02-10T00:43:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:16 volumio sudo[7571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:16 volumio sudo[7571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:17 volumio go-librespot[7553]: time="2026-02-10T00:43:17-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:17 volumio go-librespot[7553]: time="2026-02-10T00:43:17-06:00" level=debug msg="completed challenge" Feb 10 00:43:17 volumio go-librespot[7553]: time="2026-02-10T00:43:17-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 10 00:43:17 volumio volumio[7275]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 10 00:43:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:17 volumio volumio[7275]: info: Asound.conf file unchanged, so no further update is needed Feb 10 00:43:17 volumio volumio[7275]: info: Output device has changed, restarting MPD Feb 10 00:43:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 00:43:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 00:43:17 volumio volumio[7275]: info: Output device has changed, restarting Shairport Sync Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:17 volumio systemd[1]: shairport-sync.service: Consumed 2.131s CPU time. Feb 10 00:43:17 volumio sudo[7576]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 10 00:43:17 volumio sudo[7576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:17 volumio sudo[7580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 00:43:17 volumio sudo[7580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:17 volumio sudo[7576]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio sudo[7578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 00:43:17 volumio sudo[7578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:17 volumio volumio[7275]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:17 volumio sudo[7578]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:17 volumio sudo[7568]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio sudo[7571]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio sudo[7566]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 00:43:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 00:43:17 volumio systemd[1]: mpd.service: Consumed 1.535s CPU time. Feb 10 00:43:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 00:43:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 00:43:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 00:43:17 volumio volumio[7275]: info: MPD Permissions set Feb 10 00:43:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 00:43:17 volumio volumio[7275]: info: Shairport-Sync Started Feb 10 00:43:17 volumio volumio[7275]: Error adding Membership: Error: addMembership EINVAL Feb 10 00:43:17 volumio volumio[7275]: info: Shairport-Sync Started Feb 10 00:43:17 volumio volumio[7275]: info: Shairport-Sync Started Feb 10 00:43:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 00:43:17 volumio volumio[7275]: 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 10 00:43:17 volumio sudo[7611]: 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 10 00:43:17 volumio sudo[7611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:17 volumio sudo[7611]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio sudo[7616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 10 00:43:17 volumio sudo[7616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:17 volumio systemd[1]: Reloading. Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::volumioGetState Feb 10 00:43:17 volumio volumio[7275]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:17 volumio volumio[7275]: info: Starting Shairport Sync Feb 10 00:43:17 volumio sudo[7608]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 00:43:17 volumio sudo[7608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 00:43:17 volumio volumio[7275]: 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 10 00:43:17 volumio sudo[7608]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:17 volumio volumio[7275]: info: Spotify Successfully logged in Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:17 volumio volumio[7275]: info: [1770705797717] CoreMusicLibrary::Adding element Spotify Feb 10 00:43:17 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source SoundCloud Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source YouTube2 Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source YouTube Music Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source Podcast Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source Volusonic Feb 10 00:43:17 volumio volumio[7275]: Cannot find translation for source Spotify Feb 10 00:43:17 volumio sudo[7624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:17 volumio sudo[7624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:18 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:18 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:18 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:18 volumio volumio[7275]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:19 volumio volumio[7275]: info: go-librespot daemon successfully initialized Feb 10 00:43:20 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 10 00:43:20 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 10 00:43:20 volumio sudo[7616]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:20 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Feb 10 00:43:20 volumio sudo[7660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:20 volumio sudo[7660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:20 volumio sudo[7660]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:20 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 10 00:43:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:21 volumio sudo[7664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 10 00:43:21 volumio sudo[7664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:21 volumio go-librespot[7663]: go-librespot daemon starting... Feb 10 00:43:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 00:43:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 00:43:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:21 volumio systemd[1]: shairport-sync.service: Consumed 1.544s CPU time. Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=debug msg="app state loaded" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:21 volumio sudo[7624]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:21 volumio volumio[7275]: info: Shairport-Sync Started Feb 10 00:43:21 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 10 00:43:21 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 10 00:43:21 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 10 00:43:21 volumio sudo[7664]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-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 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-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 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=info msg="zeroconf server listening on port 35899" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=debug msg="obtained new client token: AAB1YCjdUNJA+rNwIEltRvTFkiAh8neOLhv05Zuy40/a3DbEbgnX3AhD7Rl05t4TorVaS10LQacOsfJIdRg/tQWJ1WlHLvj+HvZGJNB7IBJOYsm3LX6JpCh8d2N7h8G+IM/BPbrNU9MTaSJ+w3OtP21bkMSYmU0QiN633lC3fuaMDeYSoQ12lEmQj/w+tIK4kB4XhFkPO9uRsQmzju6akKx9nKABGt6CBr39h5rUJZYpJUqhoqeIfz+atw==" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43:21-06:00" level=debug msg="completed challenge" Feb 10 00:43:21 volumio go-librespot[7666]: time="2026-02-10T00:43: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 10 00:43:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:21 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:21 volumio sudo[7690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:21 volumio sudo[7690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:22 volumio sudo[7690]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:22 volumio volumio[7275]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 10 00:43:22 volumio sudo[7694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 10 00:43:22 volumio sudo[7694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:22 volumio sudo[7694]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:22 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 10 00:43:22 volumio sudo[7697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 10 00:43:22 volumio sudo[7697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:22 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 10 00:43:22 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 10 00:43:22 volumio sudo[7697]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:22 volumio volumio[7275]: info: Initializing connection to go-librespot Websocket Feb 10 00:43:22 volumio volumio[7275]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 00:43:22 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:22 volumio sudo[7704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:22 volumio sudo[7704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:22 volumio sudo[7704]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:23 volumio kernel: hwmon hwmon1: Voltage normalised Feb 10 00:43:23 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:23 volumio sudo[7708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:23 volumio sudo[7708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:23 volumio sudo[7708]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:24 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:24 volumio mpd[7658]: 2026-02-10T00:43:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 00:43:24 volumio sudo[7711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:24 volumio sudo[7711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:24 volumio sudo[7711]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 00:43:24 volumio sudo[7580]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:24 volumio sudo[7493]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:24 volumio sudo[7503]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:24 volumio volumio[7275]: error: MPD error: The expression evaluated to a falsy value: Feb 10 00:43:24 volumio volumio[7275]: assert.ok(self.idling) Feb 10 00:43:24 volumio volumio[7275]: error: The expression evaluated to a falsy value: Feb 10 00:43:24 volumio volumio[7275]: assert.ok(self.idling) Feb 10 00:43:24 volumio volumio[7275]: error: MPD error: The expression evaluated to a falsy value: Feb 10 00:43:24 volumio volumio[7275]: assert.ok(self.idling) Feb 10 00:43:24 volumio volumio[7275]: error: The expression evaluated to a falsy value: Feb 10 00:43:24 volumio volumio[7275]: assert.ok(self.idling) Feb 10 00:43:24 volumio volumio[7275]: info: MPD running with PID7658 Feb 10 00:43:24 volumio volumio[7275]: ,establishing connection Feb 10 00:43:24 volumio volumio[7275]: error: updateQueue error: null Feb 10 00:43:24 volumio volumio[7275]: error: updateQueue error: null Feb 10 00:43:24 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:24 volumio sudo[7719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:24 volumio sudo[7719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:24 volumio sudo[7719]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Feb 10 00:43:24 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:24 volumio go-librespot[7721]: go-librespot daemon starting... Feb 10 00:43:24 volumio go-librespot[7722]: time="2026-02-10T00:43:24-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:24 volumio go-librespot[7722]: time="2026-02-10T00:43:24-06:00" level=debug msg="app state loaded" Feb 10 00:43:24 volumio go-librespot[7722]: time="2026-02-10T00:43:24-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:24 volumio go-librespot[7722]: time="2026-02-10T00:43:24-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43: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-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-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 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-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 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-06:00" level=info msg="zeroconf server listening on port 41275" Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-06:00" level=debug msg="obtained new client token: AAD6FtjPV7LGgjqfcmVXMdwoftPDVKJacFe3kCFuj1+zs/z57pebQtXkjBz0QNg4nnYqRzAF5y557YVYZIVsmue6ny39seoheLS/xtHpCeEQlOr3VLNiLHvbwPr1R+f4/wB3nrjzyVIIhWHbIstm5WE6LAnnb4zXnNRhAQnvywfT9WD4LKPQ/7kAHOQ/kYl+VHpoPb+7gUno75GY8NFaWJso7NqXhRRAZaNnnwENAwZzDZu+8Vk3zQU=" Feb 10 00:43:25 volumio volumio[7275]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:25 volumio sudo[7730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:25 volumio sudo[7730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:25 volumio sudo[7730]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43:25-06:00" level=debug msg="completed challenge" Feb 10 00:43:25 volumio volumio[7275]: info: Completed starting Core Plugins Feb 10 00:43:25 volumio volumio[7275]: info: ------------------------------------------- Feb 10 00:43:25 volumio volumio[7275]: info: ----- MyVolumio plugins startup ---- Feb 10 00:43:25 volumio volumio[7275]: info: ------------------------------------------- Feb 10 00:43:25 volumio volumio[7275]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 00:43:25 volumio go-librespot[7722]: time="2026-02-10T00:43: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 10 00:43:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:25 volumio volumio[7275]: info: Initializing connection to go-librespot Websocket Feb 10 00:43:25 volumio volumio[7275]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 00:43:27 volumio sudo[7733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 00:43:27 volumio sudo[7733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:27 volumio sudo[7735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 00:43:27 volumio sudo[7735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:27 volumio sudo[7737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 00:43:27 volumio sudo[7737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:27 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 10 00:43:27 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 10 00:43:27 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 10 00:43:27 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 10 00:43:27 volumio sudo[7733]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:27 volumio sudo[7735]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:27 volumio mpd_monitor.sh[7741]: MPD Monitor Service: Starting MPD Monitor Service Feb 10 00:43:27 volumio volumio[7275]: info: Successfully started MPD Monitor Feb 10 00:43:27 volumio sudo[7737]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:27 volumio volumio[7275]: info: Successfully started MPD Monitor Feb 10 00:43:27 volumio volumio[7275]: info: Successfully started MPD Monitor Feb 10 00:43:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Feb 10 00:43:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:28 volumio go-librespot[7745]: go-librespot daemon starting... Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=debug msg="app state loaded" Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:28 volumio volumio[7275]: info: Initializing connection to go-librespot Websocket Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=debug msg="new websocket client" Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43: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 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43: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 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43: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 10 00:43:28 volumio volumio[7275]: info: Connection to go-librespot Websocket established Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=info msg="zeroconf server listening on port 34805" Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-06:00" level=debug msg="obtained new client token: AADCyMa0+R0tl1rlQlFxhOHluA+HxATtS4GR/3GduX4OD8EeUQQT+1fTtOS4T8nZMenSsddnslKHwu2lvHRMVV9xqAv8/3G/qAr7BQ7zEOPlEMDvznpJO7x2MMgIy4C8zaAAEHhLQMyzk03h3u5Px2Ob1MB+0b6JVMUbFaNRPeGIZo5XxIb8ZwkLWiK8N1yLZ6HBugqbXUn8Nt01dg6VFtgs4cZxnyxIm2dDDI2zitEx8OVBwTYaup0yVg==" Feb 10 00:43:28 volumio go-librespot[7746]: time="2026-02-10T00:43:28-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 10 00:43:29 volumio go-librespot[7746]: time="2026-02-10T00:43:29-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 10 00:43:29 volumio go-librespot[7746]: time="2026-02-10T00:43:29-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:29 volumio go-librespot[7746]: time="2026-02-10T00:43:29-06:00" level=debug msg="completed challenge" Feb 10 00:43:29 volumio go-librespot[7746]: time="2026-02-10T00:43: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 10 00:43:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:29 volumio volumio[7275]: info: Connection to go-librespot Websocket closed Feb 10 00:43:31 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 10 00:43:31 volumio volumio[7275]: info: Getting Spotify volume Feb 10 00:43:32 volumio volumio[7275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 00:43:32 volumio volumio[7275]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 10 00:43:32 volumio volumio[7275]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 10 00:43:32 volumio volumio[7275]: errno: -111, Feb 10 00:43:32 volumio volumio[7275]: code: 'ECONNREFUSED', Feb 10 00:43:32 volumio volumio[7275]: syscall: 'connect', Feb 10 00:43:32 volumio volumio[7275]: address: '127.0.0.1', Feb 10 00:43:32 volumio volumio[7275]: port: 9879, Feb 10 00:43:32 volumio volumio[7275]: response: undefined Feb 10 00:43:32 volumio volumio[7275]: } Feb 10 00:43:32 volumio volumio[7275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 00:43:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Feb 10 00:43:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:32 volumio go-librespot[7781]: go-librespot daemon starting... Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=debug msg="app state loaded" Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-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 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-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 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=info msg="zeroconf server listening on port 40099" Feb 10 00:43:32 volumio go-librespot[7782]: time="2026-02-10T00:43:32-06:00" level=debug msg="obtained new client token: AACVONiVPsCQolE6gmPURiX17Iz6yVFhJTssXR+4brQ+ja7O+ftV2Sf5XUgvk82eV/a30pvymTuAoB5XBWfYyjg5Hqkf+wFxhlGj/twSa76lFR/Sr0WeF6jEZ2NUDNw2IG5vjpxNfyoikAbCSO7hZ/utX28nkLr7uPKJqPh524fwcMN9eGIOxkB7mP3UkJc8gpp7quYloq8Dh03wRtiXcM50xe1M6uEUL1mYSJTYhDFYZxWwMxL6ZPDu7g==" Feb 10 00:43:33 volumio go-librespot[7782]: time="2026-02-10T00:43:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:33 volumio go-librespot[7782]: time="2026-02-10T00:43:33-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:33 volumio go-librespot[7782]: time="2026-02-10T00:43:33-06:00" level=debug msg="completed challenge" Feb 10 00:43:33 volumio go-librespot[7782]: time="2026-02-10T00:43:33-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 00:43:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:33 volumio sudo[7793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 00:42' Feb 10 00:43:33 volumio sudo[7793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:33 volumio sudo[7793]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:33 volumio volumio-remote-updater[751]: [2026-02-10 00:43:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 10 00:43:33 volumio volumio-remote-updater[751]: [2026-02-10 00:43:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 10 00:43:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 10 00:43:33 volumio systemd[1]: volumio.service: Consumed 47.663s CPU time. Feb 10 00:43:33 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 00:43:33 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 00:43:34 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3345. Feb 10 00:43:34 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 10 00:43:34 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 10 00:43:34 volumio systemd[1]: volumio.service: Consumed 47.663s CPU time. Feb 10 00:43:34 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 10 00:43:34 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 10 00:43:35 volumio kernel: hwmon hwmon1: Voltage normalised Feb 10 00:43:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Feb 10 00:43:36 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:43:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:36 volumio volumio[7807]: info: ----- Volumio3 ---- Feb 10 00:43:36 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:43:36 volumio volumio[7807]: info: ----- System startup ---- Feb 10 00:43:36 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:43:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:36 volumio go-librespot[7829]: go-librespot daemon starting... Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=debug msg="app state loaded" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-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 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-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 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=info msg="zeroconf server listening on port 35733" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=debug msg="obtained new client token: AACobby1kMBjsZLIw7naZLGv5W1fGl+KruhFupYTiN+ZRMouVFJKZXBsKwhb76212dzRbL955Nv7qr0A+kffvQWUzQuRxPCN1gTwRu1A3fg3KascT/Q0K15vTkNN0Y0vAe+/FfsLvajwrONW+d8U1OEspUNs9XHLbpKk8NtMvh/eaahPLiKIn594eseOTKgGaCE6TzrEufIWwEfONIvRKcKRzyEdL4DNbcdFwvjtB+xzB6oZcHk8RyRQAQ==" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-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 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-06:00" level=debug msg="completed challenge" Feb 10 00:43:36 volumio go-librespot[7830]: time="2026-02-10T00:43:36-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 10 00:43:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:37 volumio volumio[7807]: info: MYVOLUMIO Environment detected Feb 10 00:43:37 volumio volumio[7807]: info: Plugin folders cleanup Feb 10 00:43:37 volumio volumio[7807]: info: Scanning into folder /volumio/app/plugins/ Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category audio_interface Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category miscellanea Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category music_service Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category plugins.json Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category system_controller Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category user_interface Feb 10 00:43:37 volumio volumio[7807]: info: Scanning into folder /data/plugins/ Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category audio_interface Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category music_service Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category system_controller Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category system_hardware Feb 10 00:43:37 volumio volumio[7807]: info: Scanning category user_interface Feb 10 00:43:37 volumio volumio[7807]: info: Plugin folders cleanup completed Feb 10 00:43:37 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:43:37 volumio volumio[7807]: info: ----- Core plugins startup ---- Feb 10 00:43:37 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:43:37 volumio volumio[7807]: info: Loading plugins from folder /volumio/app/plugins/ Feb 10 00:43:37 volumio volumio[7807]: info: Adding plugin upnp to MyMusic Plugins Feb 10 00:43:37 volumio volumio[7807]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 10 00:43:37 volumio volumio[7807]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 10 00:43:37 volumio volumio[7807]: info: Loading plugins from folder /data/plugins/ Feb 10 00:43:37 volumio volumio[7807]: info: Loading plugin "system"... Feb 10 00:43:37 volumio volumio[7807]: info: Loading plugin "appearance"... Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "network"... Feb 10 00:43:38 volumio volumio[7807]: info: Refreshing Cached IP Addresses Feb 10 00:43:38 volumio sudo[7845]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 00:43:38 volumio sudo[7845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:38 volumio sudo[7845]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:38 volumio sudo[7847]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 00:43:38 volumio sudo[7847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "services"... Feb 10 00:43:38 volumio sudo[7847]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "alsa_controller"... Feb 10 00:43:38 volumio sudo[7855]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 10 00:43:38 volumio sudo[7855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:38 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "wizard"... Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "networkfs"... Feb 10 00:43:38 volumio volumio[7807]: info: Starting Udev Watcher for removable devices Feb 10 00:43:38 volumio volumio[7807]: info: Ignoring mount for partition: boot Feb 10 00:43:38 volumio volumio[7807]: info: Ignoring mount for partition: volumio Feb 10 00:43:38 volumio volumio[7807]: info: Ignoring mount for partition: volumio_data Feb 10 00:43:38 volumio volumio[7807]: info: Mounting Device Wikipedia Feb 10 00:43:38 volumio sudo[7884]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 10 00:43:38 volumio sudo[7884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:38 volumio sudo[7884]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:38 volumio volumio[7807]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 10 00:43:38 volumio volumio[7807]: dmesg(1) may have more information after failed mount system call. Feb 10 00:43:38 volumio volumio[7807]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 10 00:43:38 volumio volumio[7807]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 10 00:43:38 volumio volumio[7807]: dmesg(1) may have more information after failed mount system call. Feb 10 00:43:38 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "volumio_command_line_client"... Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "upnp"... Feb 10 00:43:38 volumio volumio[7807]: info: [1770705818770] Starting Upmpd Daemon Feb 10 00:43:38 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "my_music"... Feb 10 00:43:38 volumio volumio[7807]: info: Loading plugin "mpd"... Feb 10 00:43:38 volumio volumio-remote-updater[751]: [2026-02-10 00:43:38] [connect] Successful connection Feb 10 00:43:39 volumio sudo[7855]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:39 volumio volumio[7807]: info: Loading plugin "upnp_browser"... Feb 10 00:43:39 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 10 00:43:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Feb 10 00:43:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:40 volumio go-librespot[7887]: go-librespot daemon starting... Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="app state loaded" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=info msg="zeroconf server listening on port 34995" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="obtained new client token: AAD46CCrWnfePEkiHz/qSR6MhmK7DYUgeoa0b2MPPnyLa0Du9FwOBqxDAmrDcDHUBqWDrXyHixFn4dIpj8J5vv8zECQB6fbvJu1PEfq2e+wCpdQzvyHS9aaGVbtKUqyml+srtS8VJ+V9x0SGw++FzoPb3zSUVn3/cIkefppiQG4v4nbaIh10hr+/YYdxUKkXqRNbVeJ3WvEpMvBPZzCtWazZYqFPwgBZGikAGA30SbUzARZAjxScvGJZog==" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=debug msg="completed challenge" Feb 10 00:43:40 volumio go-librespot[7888]: time="2026-02-10T00:43:40-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 00:43:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "alarm-clock"... Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "airplay_emulation"... Feb 10 00:43:42 volumio volumio[7807]: info: Starting Shairport Sync Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "last_100"... Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "webradio"... Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "i2s_dacs"... Feb 10 00:43:42 volumio volumio[7807]: info: I2S DAC not set, start Auto-detection Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "volumiodiscovery"... Feb 10 00:43:42 volumio volumio[7807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:42 volumio volumio[7807]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:42 volumio volumio[7807]: *** WARNING *** For more information see Feb 10 00:43:42 volumio volumio[7807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:42 volumio volumio[7807]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:42 volumio volumio[7807]: *** WARNING *** For more information see Feb 10 00:43:42 volumio node[7807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:42 volumio node[7807]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:42 volumio node[7807]: *** WARNING *** For more information see Feb 10 00:43:42 volumio node[7807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 10 00:43:42 volumio node[7807]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 10 00:43:42 volumio node[7807]: *** WARNING *** For more information see Feb 10 00:43:42 volumio volumio[7807]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 10 00:43:42 volumio volumio[7807]: info: Discovery: Started advertising with name: Volumio Feb 10 00:43:42 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 10 00:43:42 volumio volumio[7807]: info: Loading plugin "bandcamp"... Feb 10 00:43:43 volumio volumio[7807]: info: Plugin calmradio is not enabled Feb 10 00:43:43 volumio volumio[7807]: info: Loading plugin "soundcloud"... Feb 10 00:43:43 volumio kernel: hwmon hwmon1: Voltage normalised Feb 10 00:43:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Feb 10 00:43:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:44 volumio volumio[7807]: info: Loading plugin "spop"... Feb 10 00:43:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:44 volumio go-librespot[7899]: go-librespot daemon starting... Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=debug msg="app state loaded" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43: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 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43: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 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43: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 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=info msg="zeroconf server listening on port 33155" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=debug msg="obtained new client token: AACgT9SPUO5m5ePQSBOiabzvMBPTyjiQc/s52ZZo4u31IbSc7rznfuIbnnv9k56GGBFwpnUHnQxQb7lQem/O5o4wH8vJnxMLjCAwAGosH5loCbijUVV0mEYL95ip+jP0vO5m1vTJVCPsfFA+hSzDIPGp2GdnFA5ham6xqdhbL+nqrhRRJdmVPf+OCEGCfEKXEYxBQ6YnhxBLXc8KoBCvBIZ5yGaOxw7cRtuLrnCVFniSR/NtO0rm4YnZhw==" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=debug msg="completed keyexchange" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43:44-06:00" level=debug msg="completed challenge" Feb 10 00:43:44 volumio go-librespot[7900]: time="2026-02-10T00:43: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 10 00:43:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:43:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:43:45 volumio volumio[7807]: info: Loading plugin "squeezelite_mc"... Feb 10 00:43:45 volumio volumio[7807]: info: Loading plugin "youtube2"... Feb 10 00:43:46 volumio volumio[7807]: info: Loading plugin "ytcr"... Feb 10 00:43:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Feb 10 00:43:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:47 volumio go-librespot[7910]: go-librespot daemon starting... Feb 10 00:43:47 volumio go-librespot[7911]: time="2026-02-10T00:43:47-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:47 volumio go-librespot[7911]: time="2026-02-10T00:43:47-06:00" level=debug msg="app state loaded" Feb 10 00:43:47 volumio go-librespot[7911]: time="2026-02-10T00:43:47-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:47 volumio go-librespot[7911]: time="2026-02-10T00:43:47-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:48 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 10 00:43:48 volumio go-librespot[7911]: time="2026-02-10T00:43: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 10 00:43:48 volumio go-librespot[7911]: time="2026-02-10T00:43:48-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 10 00:43:48 volumio go-librespot[7911]: time="2026-02-10T00:43:48-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 10 00:43:48 volumio go-librespot[7911]: time="2026-02-10T00:43:48-06:00" level=info msg="zeroconf server listening on port 44131" Feb 10 00:43:48 volumio volumio[7807]: info: Loading plugin "ytmusic"... Feb 10 00:43:49 volumio volumio[7807]: info: Plugin now_playing is not enabled Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "outputs"... Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "albumart"... Feb 10 00:43:49 volumio volumio[7807]: info: Plugin example_plugin is not enabled Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "inputs"... Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "updater_comm"... Feb 10 00:43:49 volumio volumio[7807]: info: Plugin mpdemulation is not enabled Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "rest_api"... Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "websocket"... Feb 10 00:43:49 volumio volumio[7807]: info: Starting Socket.io Server version 1.7.4 Feb 10 00:43:49 volumio volumio[7807]: info: Plugin fusiondsp is not enabled Feb 10 00:43:49 volumio volumio[7807]: info: Plugin mpdoutput is not enabled Feb 10 00:43:49 volumio volumio[7807]: info: Plugin RoonBridge is not enabled Feb 10 00:43:49 volumio volumio[7807]: info: Loading plugin "podcast"... Feb 10 00:43:50 volumio volumio[7807]: info: ControllerPodcast::constructor Feb 10 00:43:50 volumio volumio[7807]: info: Loading plugin "volusonic"... Feb 10 00:43:50 volumio volumio[7933]: Forking 3 albumart workers Feb 10 00:43:51 volumio volumio-remote-updater[751]: [2026-02-10 00:43:51] [connect] Successful connection Feb 10 00:43:51 volumio volumio[7807]: info: Applying required configuration parameters for plugin volusonic Feb 10 00:43:51 volumio volumio[7807]: info: Loading plugin "backup_restore"... Feb 10 00:43:51 volumio volumio[7945]: Starting albumart workers Feb 10 00:43:52 volumio volumio[7943]: Starting albumart workers Feb 10 00:43:52 volumio volumio[7807]: info: Applying required configuration parameters for plugin backup_restore Feb 10 00:43:52 volumio volumio[7807]: info: Plugin rpi_eeprom_config is not enabled Feb 10 00:43:52 volumio volumio[7807]: info: Plugin rpi_eeprom_updater is not enabled Feb 10 00:43:52 volumio volumio[7807]: info: Loading plugin "scheduledrestart"... Feb 10 00:43:52 volumio volumio[7944]: Starting albumart workers Feb 10 00:43:52 volumio kernel: hwmon hwmon1: Voltage normalised Feb 10 00:43:52 volumio volumio[7807]: info: Applying required configuration parameters for plugin scheduledrestart Feb 10 00:43:52 volumio volumio[7807]: info: Plugin Bluetoothremote is not enabled Feb 10 00:43:52 volumio volumio[7807]: info: Plugin music_services_shield is not enabled Feb 10 00:43:52 volumio volumio[7807]: info: Loading plugin "Systeminfo"... Feb 10 00:43:52 volumio volumio[7807]: info: Plugin peppymeterbasic is not enabled Feb 10 00:43:52 volumio volumio[7807]: info: Loading plugin "peppyspectrum"... Feb 10 00:43:53 volumio volumio[7807]: info: Loading i18n strings for locale en Feb 10 00:43:53 volumio volumio[7807]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 10 00:43:53 volumio volumio[7807]: Updating browse sources language Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::initPlayerControls Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: Express server listening on port 3000 Feb 10 00:43:53 volumio volumio[7807]: [Metrics] WebUI: 17s 577.30ms Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::resetVolumioState Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::getcurrentVolume Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:53 volumio sudo[7998]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 10 00:43:53 volumio sudo[7998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio sudo[8000]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 10 00:43:53 volumio sudo[8000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio sudo[7998]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:53 volumio sudo[8000]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:53 volumio volumio[7807]: info: Volumio Network Manager: Network status updated: 1 Feb 10 00:43:53 volumio volumio[7807]: info: VolumeController:: Volume=100 Mute =false Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::pushState Feb 10 00:43:53 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::updateTrackBlock Feb 10 00:43:53 volumio volumio[7807]: info: CorePlayQueue::getTrackBlock Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 00:43:53 volumio volumio-remote-updater[751]: [2026-02-10 00:43:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770705831 101 Feb 10 00:43:53 volumio volumio[7807]: 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 10 00:43:53 volumio volumio[7807]: info: Reloading queue from file Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::setRepeat null single undefined Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::pushState Feb 10 00:43:53 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::setRandom null Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::pushState Feb 10 00:43:53 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:53 volumio volumio[7807]: info: Setting Device type: Raspberry PI Feb 10 00:43:53 volumio volumio[7807]: info: VolumeController:: Volume=100 Mute =false Feb 10 00:43:53 volumio volumio[7807]: info: CoreStateMachine::pushState Feb 10 00:43:53 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:53 volumio volumio[7807]: info: Completed loading Core Plugins Feb 10 00:43:53 volumio volumio[7807]: info: Preparing to generate the ALSA configuration file Feb 10 00:43:53 volumio sudo[8014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 10 00:43:53 volumio sudo[8014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio volumio[7807]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 10 00:43:53 volumio volumio[7807]: info: Discovery: Found device Volumio Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioGetState Feb 10 00:43:53 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:53 volumio volumio[7807]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 10 00:43:53 volumio volumio[7807]: info: Reading ALSA contributions from plugins. Feb 10 00:43:53 volumio volumio[7807]: info: Asound.conf file unchanged, so no further update is needed Feb 10 00:43:53 volumio volumio[7807]: info: Output device has changed, restarting MPD Feb 10 00:43:53 volumio sudo[8014]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:53 volumio volumio[7807]: info: Output device has changed, restarting Shairport Sync Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:53 volumio sudo[8017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 00:43:53 volumio sudo[8017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio sudo[8019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 00:43:53 volumio sudo[8019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio sudo[8017]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:53 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:53 volumio volumio[7807]: info: ___________ START PLUGINS ___________ Feb 10 00:43:53 volumio volumio[7807]: info: ControllerMpd::onStart: Initializing MPD Feb 10 00:43:53 volumio volumio[7807]: info: Creating MPD Configuration file Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:53 volumio volumio[7807]: info: [1770705833901] CoreMusicLibrary::Adding element Media Servers Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:53 volumio sudo[8027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 00:43:53 volumio sudo[8027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio sudo[8027]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:53 volumio sudo[8029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 00:43:53 volumio sudo[8029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:53 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:53 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:54 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834030] CoreMusicLibrary::Adding element Last_100 Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834034] CoreMusicLibrary::Adding element Webradio Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 00:43:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 00:43:54 volumio systemd[1]: mpd.service: Consumed 4.264s CPU time. Feb 10 00:43:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 00:43:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 00:43:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 00:43:54 volumio volumio[7807]: info: Initializing BBC Radios Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834117] CoreMusicLibrary::Adding element Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834131] CoreMusicLibrary::Adding element SoundCloud Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: info: Creating Spotify config file Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:54 volumio sudo[8054]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 00:43:54 volumio sudo[8054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 00:43:54 volumio sudo[8054]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:54 volumio volumio[7807]: info: [squeezelite_mc] Starting proxy server... Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834482] CoreMusicLibrary::Adding element YouTube2 Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834501] CoreMusicLibrary::Adding element YouTube Music Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube Music Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834513] CoreMusicLibrary::Adding element Podcast Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube Music Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Podcast Feb 10 00:43:54 volumio volumio[7807]: info: Loading i18n strings for locale en Feb 10 00:43:54 volumio volumio[7807]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 10 00:43:54 volumio volumio[7807]: Updating browse sources language Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube Music Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Podcast Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube Music Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Podcast Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:43:54 volumio volumio[7807]: info: [1770705834569] CoreMusicLibrary::Adding element Volusonic Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source YouTube Music Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Podcast Feb 10 00:43:54 volumio volumio[7807]: Cannot find translation for source Volusonic Feb 10 00:43:54 volumio volumio[7807]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 10 00:43:54 volumio volumio[7807]: info: Volumio Calling Home Feb 10 00:43:54 volumio volumio[7807]: info: [squeezelite_mc] Proxy server started on port 46113 Feb 10 00:43:54 volumio volumio[7807]: info: Preparing to generate the ALSA configuration file Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioRetrievevolume Feb 10 00:43:54 volumio volumio[7807]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 10 00:43:54 volumio volumio[7807]: info: Discovery: Found device Volumio Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioGetState Feb 10 00:43:54 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:54 volumio volumio[7807]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 10 00:43:54 volumio volumio[7807]: info: Reading ALSA contributions from plugins. Feb 10 00:43:54 volumio volumio[7807]: info: MPD Permissions set Feb 10 00:43:54 volumio volumio[7807]: info: MPD Permissions set Feb 10 00:43:54 volumio volumio[7807]: info: Upmpdcli Daemon Started Feb 10 00:43:54 volumio volumio[7807]: info: Spotify config file written Feb 10 00:43:54 volumio sudo[8063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 10 00:43:54 volumio sudo[8063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:54 volumio volumio[7807]: 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 10 00:43:54 volumio volumio[7807]: info: VolumeController:: Volume=100 Mute =false Feb 10 00:43:54 volumio volumio[7807]: info: CoreStateMachine::pushState Feb 10 00:43:54 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::volumioPushState Feb 10 00:43:54 volumio volumio[7807]: info: [squeezelite_mc] Server discovery started Feb 10 00:43:54 volumio volumio[7807]: info: [squeezelite_mc] Player finder started Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:54 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:54 volumio volumio[7807]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 10 00:43:55 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 10 00:43:55 volumio systemd[1]: go-librespot-daemon.service: Killing process 7916 (go-librespot) with signal SIGKILL. Feb 10 00:43:55 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 10 00:43:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 10 00:43:55 volumio go-librespot[8068]: go-librespot daemon starting... Feb 10 00:43:55 volumio sudo[8063]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: No need to fix Spotify hosts Feb 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-06:00" level=info msg="running go-librespot 0.4.0" Feb 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-06:00" level=debug msg="app state loaded" Feb 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-06:00" level=debug msg="stored credentials not found" Feb 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 10 00:43:55 volumio volumio[7807]: 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 10 00:43:55 volumio volumio[7807]: info: Volumio called home Feb 10 00:43:55 volumio volumio[7807]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 10 00:43:55 volumio volumio[7807]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 10 00:43:55 volumio volumio[7807]: 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 10 00:43:55 volumio volumio[7807]: info: Starting Shairport Sync Feb 10 00:43:55 volumio volumio[7807]: info: Starting Shairport Sync Feb 10 00:43:55 volumio sudo[8088]: 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 10 00:43:55 volumio sudo[8088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio sudo[8088]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio volumio[7807]: info: Starting Shairport Sync Feb 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-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 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-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 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-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 10 00:43:55 volumio go-librespot[8076]: time="2026-02-10T00:43:55-06:00" level=info msg="zeroconf server listening on port 34531" Feb 10 00:43:55 volumio sudo[8091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:55 volumio sudo[8091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio volumio[7807]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 10 00:43:55 volumio sudo[8094]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:55 volumio sudo[8094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio sudo[8096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:55 volumio sudo[8096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio volumio[7807]: info: Asound.conf file unchanged, so no further update is needed Feb 10 00:43:55 volumio volumio[7807]: info: Output device has changed, restarting MPD Feb 10 00:43:55 volumio volumio[7807]: info: Output device has changed, restarting Shairport Sync Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 00:43:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 00:43:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:55 volumio systemd[1]: shairport-sync.service: Consumed 1.992s CPU time. Feb 10 00:43:55 volumio sudo[8107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 00:43:55 volumio sudo[8107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio sudo[8102]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 10 00:43:55 volumio sudo[8102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio sudo[8104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 00:43:55 volumio sudo[8104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio sudo[8104]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio volumio[7807]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 00:43:55 volumio sudo[8102]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:55 volumio volumio[7807]: info: MPD Permissions set Feb 10 00:43:55 volumio sudo[8091]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio volumio[7807]: 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 10 00:43:55 volumio sudo[8096]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 00:43:55 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 00:43:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 00:43:55 volumio systemd[1]: mpd.service: Consumed 1.539s CPU time. Feb 10 00:43:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 00:43:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 00:43:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 00:43:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 00:43:55 volumio sudo[8120]: 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 10 00:43:55 volumio sudo[8120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 00:43:55 volumio sudo[8120]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 00:43:55 volumio volumio[7807]: info: Shairport-Sync Started Feb 10 00:43:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:55 volumio volumio[7807]: Error adding Membership: Error: addMembership EINVAL Feb 10 00:43:55 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 10 00:43:55 volumio sudo[8094]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:55 volumio volumio[7807]: info: Shairport-Sync Started Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio sudo[8127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 00:43:55 volumio sudo[8127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:55 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:55 volumio systemd[1]: Reloading. Feb 10 00:43:55 volumio volumio[7807]: info: Shairport-Sync Started Feb 10 00:43:56 volumio volumio[7807]: info: CoreCommandRouter::volumioGetState Feb 10 00:43:56 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:43:56 volumio volumio[7807]: info: Starting Shairport Sync Feb 10 00:43:56 volumio sudo[8124]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 00:43:56 volumio sudo[8124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 00:43:56 volumio sudo[8124]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:56 volumio sudo[8149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 00:43:56 volumio sudo[8149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:56 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 10 00:43:57 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:57 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:57 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 00:43:57 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 10 00:43:58 volumio volumio[7807]: info: go-librespot daemon successfully initialized Feb 10 00:43:58 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 10 00:43:58 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 10 00:43:59 volumio sudo[8127]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:59 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:43:59 volumio sudo[8186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:43:59 volumio sudo[8186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:59 volumio sudo[8186]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:59 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 10 00:43:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 00:43:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 00:43:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:59 volumio systemd[1]: shairport-sync.service: Consumed 1.545s CPU time. Feb 10 00:43:59 volumio sudo[8190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 10 00:43:59 volumio sudo[8190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:43:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 00:43:59 volumio sudo[8149]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:59 volumio volumio[7807]: info: Shairport-Sync Started Feb 10 00:43:59 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 10 00:43:59 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 10 00:43:59 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 10 00:43:59 volumio sudo[8190]: pam_unix(sudo:session): session closed for user root Feb 10 00:43:59 volumio volumio[7807]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 00:43:59 volumio volumio[7807]: SPOTIFY: BQCeaJiVbkaI5bN2nf4J6Fdll4PvAJm8GT-wuO-mZUTo64oH37gZhPSnNooNlRuZlyTOD_RiJztf8DfF5e1S5iS7MoSmICEuppurYT5oCgDL1mM2CiO4-DSuvCXqub2FDfqEm-c6iJl_4O5Q64_ygP7_1gjH0Ib2U8goDQE6ThQn5aM5qh_V_YResp4yqd61NRecE7KydUKxPJ6ZALjVWZmSvIiMcvrdsIe2UhI Feb 10 00:43:59 volumio volumio[7807]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 10 00:43:59 volumio volumio[7807]: info: New Spotify access token = BQCeaJiVbkaI5bN2nf4J6Fdll4PvAJm8GT-wuO-mZUTo64oH37gZhPSnNooNlRuZlyTOD_RiJztf8DfF5e1S5iS7MoSmICEuppurYT5oCgDL1mM2CiO4-DSuvCXqub2FDfqEm-c6iJl_4O5Q64_ygP7_1gjH0Ib2U8goDQE6ThQn5aM5qh_V_YResp4yqd61NRecE7KydUKxPJ6ZALjVWZmSvIiMcvrdsIe2UhI Feb 10 00:43:59 volumio volumio[7807]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 10 00:44:00 volumio volumio[7807]: 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 10 00:44:00 volumio volumio[7807]: info: Spotify Successfully logged in Feb 10 00:44:00 volumio volumio[7807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 00:44:00 volumio volumio[7807]: info: [1770705840099] CoreMusicLibrary::Adding element Spotify Feb 10 00:44:00 volumio volumio[7807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source Bandcamp Discover Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source SoundCloud Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source YouTube2 Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source YouTube Music Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source Podcast Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source Volusonic Feb 10 00:44:00 volumio volumio[7807]: Cannot find translation for source Spotify Feb 10 00:44:00 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:44:00 volumio sudo[8207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:44:00 volumio sudo[8207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:00 volumio sudo[8207]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:00 volumio volumio[7807]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 10 00:44:00 volumio sudo[8211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 10 00:44:00 volumio sudo[8211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:00 volumio sudo[8211]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:00 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 10 00:44:00 volumio sudo[8214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 10 00:44:00 volumio sudo[8214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:00 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 10 00:44:00 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 10 00:44:00 volumio sudo[8214]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:01 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:44:01 volumio sudo[8221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:44:01 volumio sudo[8221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:01 volumio sudo[8221]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:01 volumio volumio[7807]: info: Initializing connection to go-librespot Websocket Feb 10 00:44:01 volumio go-librespot[8076]: time="2026-02-10T00:44:01-06:00" level=debug msg="new websocket client" Feb 10 00:44:01 volumio volumio[7807]: info: Connection to go-librespot Websocket established Feb 10 00:44:01 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:44:01 volumio sudo[8224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:44:01 volumio sudo[8224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:01 volumio sudo[8224]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:02 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:44:02 volumio sudo[8227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:44:02 volumio sudo[8227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:02 volumio sudo[8227]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:02 volumio mpd[8184]: 2026-02-10T00:44:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 00:44:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 00:44:02 volumio sudo[8029]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:02 volumio sudo[8019]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:02 volumio sudo[8107]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:02 volumio kernel: hwmon hwmon1: Voltage normalised Feb 10 00:44:02 volumio volumio[7807]: error: MPD error: The expression evaluated to a falsy value: Feb 10 00:44:02 volumio volumio[7807]: assert.ok(self.idling) Feb 10 00:44:02 volumio volumio[7807]: error: The expression evaluated to a falsy value: Feb 10 00:44:02 volumio volumio[7807]: assert.ok(self.idling) Feb 10 00:44:02 volumio volumio[7807]: error: MPD error: The expression evaluated to a falsy value: Feb 10 00:44:02 volumio volumio[7807]: assert.ok(self.idling) Feb 10 00:44:02 volumio volumio[7807]: error: The expression evaluated to a falsy value: Feb 10 00:44:02 volumio volumio[7807]: assert.ok(self.idling) Feb 10 00:44:02 volumio volumio[7807]: info: MPD running with PID8184 Feb 10 00:44:02 volumio volumio[7807]: ,establishing connection Feb 10 00:44:02 volumio volumio[7807]: error: updateQueue error: null Feb 10 00:44:02 volumio volumio[7807]: error: updateQueue error: null Feb 10 00:44:02 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:44:02 volumio sudo[8236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:44:02 volumio sudo[8236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:03 volumio sudo[8236]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:03 volumio volumio[7807]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 10 00:44:03 volumio sudo[8239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 10 00:44:03 volumio sudo[8239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:03 volumio sudo[8239]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:03 volumio volumio[7807]: info: Completed starting Core Plugins Feb 10 00:44:03 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:44:03 volumio volumio[7807]: info: ----- MyVolumio plugins startup ---- Feb 10 00:44:03 volumio volumio[7807]: info: ------------------------------------------- Feb 10 00:44:03 volumio volumio[7807]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 10 00:44:04 volumio volumio[7807]: info: Getting Spotify volume Feb 10 00:44:04 volumio volumio[7807]: 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 10 00:44:04 volumio volumio[7807]: info: CoreCommandRouter::volumioGetState Feb 10 00:44:04 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:44:04 volumio volumio[7807]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 10 00:44:04 volumio volumio[7807]: SPOTIFY: SPOTIFY VOLUME undefined Feb 10 00:44:04 volumio volumio[7807]: SPOTIFY: VOLUMIO VOLUME 100 Feb 10 00:44:04 volumio volumio[7807]: info: Aligning Spotify Volume to Volumio Volume Feb 10 00:44:04 volumio volumio[7807]: info: CoreCommandRouter::volumioGetState Feb 10 00:44:04 volumio volumio[7807]: info: CorePlayQueue::getTrack 0 Feb 10 00:44:04 volumio volumio[7807]: info: Setting Spotify Volume from Volumio: 100 Feb 10 00:44:05 volumio go-librespot[8076]: time="2026-02-10T00:44:05-06:00" level=debug msg="obtained new client token: AADZQ5aFP5dv41j26hLhWY2GGLm/bOgxt5Gv8o1XUAIIQlRas/W+9OKCVKW55qJx2kbtoT6I8nTPLKlL0uot5LoAHwbw1lvqSO8Qkk01KLvTw7pbNKs+92x1t+KR971T8JitPGLffzcqAeWJUxAKnwcu6eslNnyjvrfrStaAVMU+PMbGlFjKmUT6/0d+/waFpNmfFGmcgkFPYrOkDmonxvua4QwjPTxD+/XPi/fugo9tH0TuRcVGNQctXg==" Feb 10 00:44:05 volumio go-librespot[8076]: time="2026-02-10T00:44:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 10 00:44:05 volumio go-librespot[8076]: time="2026-02-10T00:44:05-06:00" level=debug msg="completed keyexchange" Feb 10 00:44:05 volumio go-librespot[8076]: time="2026-02-10T00:44:05-06:00" level=debug msg="completed challenge" Feb 10 00:44:05 volumio sudo[8242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 00:44:05 volumio sudo[8242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:05 volumio sudo[8244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 00:44:05 volumio sudo[8244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:05 volumio sudo[8246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 00:44:05 volumio go-librespot[8076]: time="2026-02-10T00:44:05-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 10 00:44:05 volumio sudo[8246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 00:44:05 volumio volumio[7807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 00:44:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 10 00:44:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 10 00:44:05 volumio volumio[7807]: Error: socket hang up Feb 10 00:44:05 volumio volumio[7807]: at connResetException (node:internal/errors:720:14) Feb 10 00:44:05 volumio volumio[7807]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 10 00:44:05 volumio volumio[7807]: at Socket.emit (node:events:526:35) Feb 10 00:44:05 volumio volumio[7807]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 10 00:44:05 volumio volumio[7807]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 10 00:44:05 volumio volumio[7807]: code: 'ECONNRESET', Feb 10 00:44:05 volumio volumio[7807]: response: undefined Feb 10 00:44:05 volumio volumio[7807]: } Feb 10 00:44:05 volumio volumio[7807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 00:44:05 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 10 00:44:05 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 10 00:44:05 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 10 00:44:05 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 10 00:44:05 volumio sudo[8244]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:05 volumio mpd_monitor.sh[8261]: MPD Monitor Service: Starting MPD Monitor Service Feb 10 00:44:05 volumio sudo[8246]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:05 volumio sudo[8242]: pam_unix(sudo:session): session closed for user root Feb 10 00:44:06 volumio sudo[8268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 00:43' Feb 10 00:44:06 volumio sudo[8268]: 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"