Feb 08 12:43:00 volumio volumio[24692]: info: Loading plugin "ytmusic"... Feb 08 12:43:00 volumio volumio[24692]: info: Plugin now_playing is not enabled Feb 08 12:43:00 volumio volumio[24692]: info: Loading plugin "outputs"... Feb 08 12:43:00 volumio volumio[24692]: info: Loading plugin "albumart"... Feb 08 12:43:01 volumio volumio[24692]: info: Plugin example_plugin is not enabled Feb 08 12:43:01 volumio volumio[24692]: info: Loading plugin "inputs"... Feb 08 12:43:01 volumio volumio[24692]: info: Loading plugin "updater_comm"... Feb 08 12:43:01 volumio volumio[24692]: info: Plugin mpdemulation is not enabled Feb 08 12:43:01 volumio volumio[24692]: info: Loading plugin "rest_api"... Feb 08 12:43:01 volumio volumio[24692]: info: Loading plugin "websocket"... Feb 08 12:43:01 volumio volumio[24692]: info: Starting Socket.io Server version 1.7.4 Feb 08 12:43:01 volumio volumio[24692]: info: Plugin fusiondsp is not enabled Feb 08 12:43:01 volumio volumio[24692]: info: Plugin mpdoutput is not enabled Feb 08 12:43:01 volumio volumio[24692]: info: Plugin RoonBridge is not enabled Feb 08 12:43:01 volumio volumio[24692]: info: Loading plugin "podcast"... Feb 08 12:43:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75. Feb 08 12:43:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:01 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:01 volumio go-librespot[24819]: go-librespot daemon starting... Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="app state loaded" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:01 volumio volumio[24692]: info: ControllerPodcast::constructor Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:01 volumio volumio[24692]: info: Loading plugin "volusonic"... Feb 08 12:43:01 volumio volumio[24795]: Forking 3 albumart workers Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=info msg="zeroconf server listening on port 45733" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="obtained new client token: AAAUMuHuAxK7Tyyj0/qyge//Zznq8LU5x6OOk10FfrdxAYYRx8EMRb9j+UgGnextl3tkYWVmjba8RXFztSM1zBFvK3PwC8lTaY0JG/yBaReprHCMx749l5K7qECsNDdutJsoQ2nP0n6Q2sqfWC4FnPSU0RIqInetuwv2QAJbDqO6TTApAnaGPlYKM1u2kN2dHoDQ08wWxHE2TII2Ka1VM910rqqglF1rngFVTuJi/jAv96SjOw0UDf+YlQ==" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=debug msg="completed challenge" Feb 08 12:43:01 volumio go-librespot[24820]: time="2026-02-08T12:43:01-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:03 volumio volumio[24692]: info: Applying required configuration parameters for plugin volusonic Feb 08 12:43:03 volumio volumio[24692]: info: Loading plugin "backup_restore"... Feb 08 12:43:03 volumio volumio[24829]: Starting albumart workers Feb 08 12:43:03 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:43:03 volumio volumio[24827]: Starting albumart workers Feb 08 12:43:03 volumio volumio[24692]: info: Applying required configuration parameters for plugin backup_restore Feb 08 12:43:03 volumio volumio[24692]: info: Plugin rpi_eeprom_config is not enabled Feb 08 12:43:03 volumio volumio[24692]: info: Plugin rpi_eeprom_updater is not enabled Feb 08 12:43:03 volumio volumio[24692]: info: Loading plugin "scheduledrestart"... Feb 08 12:43:03 volumio volumio[24828]: Starting albumart workers Feb 08 12:43:04 volumio volumio[24692]: info: Applying required configuration parameters for plugin scheduledrestart Feb 08 12:43:04 volumio volumio[24692]: info: Plugin Bluetoothremote is not enabled Feb 08 12:43:04 volumio volumio[24692]: info: Plugin music_services_shield is not enabled Feb 08 12:43:04 volumio volumio[24692]: info: Loading plugin "Systeminfo"... Feb 08 12:43:04 volumio volumio-remote-updater[751]: [2026-02-08 12:43:04] [connect] Successful connection Feb 08 12:43:04 volumio volumio[24692]: info: Plugin peppymeterbasic is not enabled Feb 08 12:43:04 volumio volumio[24692]: info: Loading plugin "peppyspectrum"... Feb 08 12:43:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76. Feb 08 12:43:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:05 volumio go-librespot[24865]: go-librespot daemon starting... Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="app state loaded" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-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 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-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 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=info msg="zeroconf server listening on port 45311" Feb 08 12:43:05 volumio volumio[24692]: info: Loading i18n strings for locale en Feb 08 12:43:05 volumio volumio[24692]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 12:43:05 volumio volumio[24692]: Updating browse sources language Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="obtained new client token: AABTGmhsOqsG1bDJr9qVbH3E1ZPk/YiRE7YMJmpX19SZTcruqGLTnDRhNKnlcC781DWUuE3rmWzPgzMYI+BkrZRqHDVMgqR9Vmxkvogr6uWW5LyMoSkiytfIX+UmElZ9mPn6LaaoWwwRWdbnOR3hWKLsTjr/ZK/n74YQg5mym+al5MH1SMWk+8+WZ/+PNUZaB2ZvPn58HBOU6UXxBFiArjUkdv+dKwsoldbQNwKN2AAxvJn6CiVkXk1BIA==" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43:05-06:00" level=debug msg="completed challenge" Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::initPlayerControls Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:05 volumio volumio[24692]: Express server listening on port 3000 Feb 08 12:43:05 volumio volumio[24692]: [Metrics] WebUI: 17s 408.96ms Feb 08 12:43:05 volumio go-librespot[24872]: time="2026-02-08T12:43: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 08 12:43:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:05 volumio volumio[24692]: info: CoreStateMachine::resetVolumioState Feb 08 12:43:05 volumio volumio[24692]: info: CoreStateMachine::getcurrentVolume Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:43:05 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:06 volumio sudo[24889]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 12:43:06 volumio sudo[24889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio sudo[24889]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:06 volumio sudo[24891]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 12:43:06 volumio sudo[24891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio sudo[24891]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:06 volumio volumio[24692]: info: Volumio Network Manager: Network status updated: 1 Feb 08 12:43:06 volumio volumio[24692]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::pushState Feb 08 12:43:06 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::updateTrackBlock Feb 08 12:43:06 volumio volumio[24692]: info: CorePlayQueue::getTrackBlock Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:43:06 volumio volumio-remote-updater[751]: [2026-02-08 12:43:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770576184 101 Feb 08 12:43:06 volumio volumio[24692]: 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 08 12:43:06 volumio volumio[24692]: info: Reloading queue from file Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::setRepeat null single undefined Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::pushState Feb 08 12:43:06 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::setRandom null Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::pushState Feb 08 12:43:06 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:06 volumio volumio[24692]: info: Setting Device type: Raspberry PI Feb 08 12:43:06 volumio volumio[24692]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:43:06 volumio volumio[24692]: info: CoreStateMachine::pushState Feb 08 12:43:06 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:06 volumio volumio[24692]: info: Completed loading Core Plugins Feb 08 12:43:06 volumio volumio[24692]: info: Preparing to generate the ALSA configuration file Feb 08 12:43:06 volumio sudo[24905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 12:43:06 volumio sudo[24905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio volumio[24692]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 12:43:06 volumio volumio[24692]: info: Discovery: Found device Volumio Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioGetState Feb 08 12:43:06 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:06 volumio volumio[24692]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 12:43:06 volumio volumio[24692]: info: Reading ALSA contributions from plugins. Feb 08 12:43:06 volumio volumio[24692]: info: Asound.conf file unchanged, so no further update is needed Feb 08 12:43:06 volumio volumio[24692]: info: Output device has changed, restarting MPD Feb 08 12:43:06 volumio sudo[24905]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:06 volumio volumio[24692]: info: Output device has changed, restarting Shairport Sync Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:06 volumio sudo[24908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:43:06 volumio sudo[24908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio sudo[24908]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:06 volumio sudo[24910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:43:06 volumio sudo[24910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio volumio[24692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:06 volumio volumio[24692]: info: ___________ START PLUGINS ___________ Feb 08 12:43:06 volumio volumio[24692]: info: ControllerMpd::onStart: Initializing MPD Feb 08 12:43:06 volumio volumio[24692]: info: Creating MPD Configuration file Feb 08 12:43:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:06 volumio volumio[24692]: info: [1770576186530] CoreMusicLibrary::Adding element Media Servers Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:06 volumio sudo[24918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:43:06 volumio sudo[24918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio sudo[24920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:43:06 volumio sudo[24920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:06 volumio sudo[24918]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:06 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 12:43:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 12:43:06 volumio systemd[1]: mpd.service: Consumed 5.706s CPU time. Feb 08 12:43:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 12:43:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 12:43:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 12:43:06 volumio volumio[24692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:06 volumio volumio[24692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:06 volumio volumio[24692]: info: [1770576186665] CoreMusicLibrary::Adding element Last_100 Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:06 volumio volumio[24692]: info: [1770576186669] CoreMusicLibrary::Adding element Webradio Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:43:06 volumio volumio[24692]: info: Initializing BBC Radios Feb 08 12:43:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 12:43:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:06 volumio volumio[24692]: info: [1770576186754] CoreMusicLibrary::Adding element Bandcamp Discover Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:06 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:06 volumio volumio[24692]: info: [1770576186765] CoreMusicLibrary::Adding element SoundCloud Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:06 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:06 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:06 volumio volumio[24692]: info: Creating Spotify config file Feb 08 12:43:06 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:06 volumio sudo[24936]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 12:43:06 volumio sudo[24936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 12:43:06 volumio sudo[24936]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:07 volumio volumio[24692]: info: [squeezelite_mc] Starting proxy server... Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:07 volumio volumio[24692]: info: [1770576187133] CoreMusicLibrary::Adding element YouTube2 Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:07 volumio volumio[24692]: info: [1770576187152] CoreMusicLibrary::Adding element YouTube Music Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube Music Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:07 volumio volumio[24692]: info: [1770576187163] CoreMusicLibrary::Adding element Podcast Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube Music Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Podcast Feb 08 12:43:07 volumio volumio[24692]: info: Loading i18n strings for locale en Feb 08 12:43:07 volumio volumio[24692]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 12:43:07 volumio volumio[24692]: Updating browse sources language Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube Music Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Podcast Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube Music Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Podcast Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:07 volumio volumio[24692]: info: [1770576187214] CoreMusicLibrary::Adding element Volusonic Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source YouTube Music Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Podcast Feb 08 12:43:07 volumio volumio[24692]: Cannot find translation for source Volusonic Feb 08 12:43:07 volumio volumio[24692]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 12:43:07 volumio volumio[24692]: info: Volumio Calling Home Feb 08 12:43:07 volumio volumio[24692]: info: [squeezelite_mc] Proxy server started on port 34367 Feb 08 12:43:07 volumio volumio[24692]: info: Preparing to generate the ALSA configuration file Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:43:07 volumio volumio[24692]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 12:43:07 volumio volumio[24692]: info: Discovery: Found device Volumio Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioGetState Feb 08 12:43:07 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:07 volumio volumio[24692]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 12:43:07 volumio volumio[24692]: info: Reading ALSA contributions from plugins. Feb 08 12:43:07 volumio volumio[24692]: info: MPD Permissions set Feb 08 12:43:07 volumio volumio[24692]: info: MPD Permissions set Feb 08 12:43:07 volumio volumio[24692]: info: Upmpdcli Daemon Started Feb 08 12:43:07 volumio volumio[24692]: info: Spotify config file written Feb 08 12:43:07 volumio sudo[24954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 12:43:07 volumio sudo[24954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:07 volumio volumio[24692]: 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 08 12:43:07 volumio volumio[24692]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:43:07 volumio volumio[24692]: info: CoreStateMachine::pushState Feb 08 12:43:07 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:07 volumio volumio[24692]: info: [squeezelite_mc] Server discovery started Feb 08 12:43:07 volumio volumio[24692]: info: [squeezelite_mc] Player finder started Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:07 volumio go-librespot[24956]: go-librespot daemon starting... Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio sudo[24954]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:07 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:07 volumio go-librespot[24964]: time="2026-02-08T12:43:07-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:07 volumio go-librespot[24964]: time="2026-02-08T12:43:07-06:00" level=debug msg="app state loaded" Feb 08 12:43:07 volumio go-librespot[24964]: time="2026-02-08T12:43:07-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:07 volumio volumio[24692]: info: No need to fix Spotify hosts Feb 08 12:43:07 volumio go-librespot[24964]: time="2026-02-08T12:43:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:07 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:43:07 volumio volumio[24692]: 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 08 12:43:07 volumio volumio[24692]: info: Volumio called home Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=info msg="zeroconf server listening on port 35015" Feb 08 12:43:08 volumio volumio[24692]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 08 12:43:08 volumio volumio[24692]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 08 12:43:08 volumio volumio[24692]: 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 08 12:43:08 volumio volumio[24692]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 12:43:08 volumio volumio[24692]: SPOTIFY: BQBYoFjmwqlBhKPfEpmAr_wPSTivdnGPf-5DgYNRseL-sEWpxlRlPGyyr3BXV_MlV2cj9O47nV0_HhuBmpev02QkVR4WiT-NbjY6bRXnM8bLG13DDW3SaJ_gsMiw46zJ-tl5qWVJWahAhlDxCfQvwBBC_r53i3QXJn2NeCvygRegxKmpllrIkzj5DF922RCbrhVpaqaz4TBSPNtk9hNltIrkUVdfbZGJYaqDj4I Feb 08 12:43:08 volumio volumio[24692]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 12:43:08 volumio volumio[24692]: info: New Spotify access token = BQBYoFjmwqlBhKPfEpmAr_wPSTivdnGPf-5DgYNRseL-sEWpxlRlPGyyr3BXV_MlV2cj9O47nV0_HhuBmpev02QkVR4WiT-NbjY6bRXnM8bLG13DDW3SaJ_gsMiw46zJ-tl5qWVJWahAhlDxCfQvwBBC_r53i3QXJn2NeCvygRegxKmpllrIkzj5DF922RCbrhVpaqaz4TBSPNtk9hNltIrkUVdfbZGJYaqDj4I Feb 08 12:43:08 volumio volumio[24692]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 12:43:08 volumio sudo[24980]: 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 08 12:43:08 volumio sudo[24980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="obtained new client token: AAB4AycpE1bwYHenIhUJkpI1ruJEqva4Is/3zpVFCsUlKOpLgB/3URWtCkyepWEgrm0pG0TSJKy2mmOKo3+q26QSkJbu7wdfkL+hGriZSDFgT5+aO1opRGUbv/MukHkzwGl5XZdB2s+zfQNiG/F4Nl4+iBrL/AaLC87U1NIAzQVxwuWqhLT3V6it9+0WIZnMZyzeRTS0VttiNomD211nPBGlXLaMj++Oh7NSPquOHlENjW8JjltdLzk=" Feb 08 12:43:08 volumio sudo[24980]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio volumio[24692]: info: Starting Shairport Sync Feb 08 12:43:08 volumio volumio[24692]: info: Starting Shairport Sync Feb 08 12:43:08 volumio volumio[24692]: info: Starting Shairport Sync Feb 08 12:43:08 volumio sudo[24984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:08 volumio sudo[24984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio sudo[24986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:08 volumio sudo[24986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio volumio[24692]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 08 12:43:08 volumio sudo[24989]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:08 volumio sudo[24989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:08 volumio sudo[24994]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 08 12:43:08 volumio sudo[24994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio sudo[24994]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=debug msg="completed challenge" Feb 08 12:43:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:43:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:43:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:08 volumio systemd[1]: shairport-sync.service: Consumed 2.068s CPU time. Feb 08 12:43:08 volumio go-librespot[24964]: time="2026-02-08T12:43:08-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:08 volumio volumio[24692]: info: Asound.conf file unchanged, so no further update is needed Feb 08 12:43:08 volumio volumio[24692]: info: Output device has changed, restarting MPD Feb 08 12:43:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:08 volumio volumio[24692]: info: Output device has changed, restarting Shairport Sync Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:08 volumio sudo[24986]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio sudo[24984]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio sudo[24989]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio volumio[24692]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:08 volumio sudo[24999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:43:08 volumio sudo[24999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio sudo[24999]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio sudo[25002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:43:08 volumio sudo[25002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio volumio[24692]: 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 08 12:43:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 12:43:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 12:43:08 volumio systemd[1]: mpd.service: Consumed 1.839s CPU time. Feb 08 12:43:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 12:43:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 12:43:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 12:43:08 volumio sudo[25027]: 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 08 12:43:08 volumio sudo[25027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio sudo[25027]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:08 volumio volumio[24692]: info: MPD Permissions set Feb 08 12:43:08 volumio volumio[24692]: info: Shairport-Sync Started Feb 08 12:43:08 volumio volumio[24692]: Error adding Membership: Error: addMembership EINVAL Feb 08 12:43:08 volumio volumio[24692]: info: Shairport-Sync Started Feb 08 12:43:08 volumio volumio[24692]: info: Shairport-Sync Started Feb 08 12:43:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 12:43:08 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 08 12:43:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio sudo[25033]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 08 12:43:08 volumio sudo[25033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:08 volumio systemd[1]: Reloading. Feb 08 12:43:08 volumio volumio[24692]: info: CoreCommandRouter::volumioGetState Feb 08 12:43:08 volumio volumio[24692]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:08 volumio volumio[24692]: info: Starting Shairport Sync Feb 08 12:43:09 volumio sudo[25041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:09 volumio sudo[25041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:09 volumio sudo[25031]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 12:43:09 volumio sudo[25031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 12:43:09 volumio volumio[24692]: 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 08 12:43:09 volumio sudo[25031]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:09 volumio volumio[24692]: info: Spotify Successfully logged in Feb 08 12:43:09 volumio volumio[24692]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:09 volumio volumio[24692]: info: [1770576189327] CoreMusicLibrary::Adding element Spotify Feb 08 12:43:09 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source SoundCloud Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source YouTube2 Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source YouTube Music Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source Podcast Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source Volusonic Feb 08 12:43:09 volumio volumio[24692]: Cannot find translation for source Spotify Feb 08 12:43:10 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:10 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:10 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:10 volumio volumio[24692]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:43:11 volumio volumio[24692]: info: go-librespot daemon successfully initialized Feb 08 12:43:11 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 08 12:43:11 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 08 12:43:12 volumio sudo[25033]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:12 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:12 volumio sudo[25077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:12 volumio sudo[25077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77. Feb 08 12:43:12 volumio sudo[25077]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:12 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 08 12:43:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:12 volumio sudo[25081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 08 12:43:12 volumio sudo[25081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:12 volumio go-librespot[25080]: go-librespot daemon starting... Feb 08 12:43:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:43:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:43:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:12 volumio systemd[1]: shairport-sync.service: Consumed 1.435s CPU time. Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=debug msg="app state loaded" Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:12 volumio sudo[25041]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:12 volumio volumio[24692]: info: Shairport-Sync Started Feb 08 12:43:12 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 12:43:12 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 08 12:43:12 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-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 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-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 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=info msg="zeroconf server listening on port 37677" Feb 08 12:43:12 volumio sudo[25081]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=debug msg="obtained new client token: AAACZI3Q1etwEt/OjzuUe+YtY7+5kzvk8I7SUqj9GrwGbTPx0sMeSxINwcDkXHUbaca5dcNrThQuESbqx1o4taCpUdNj9KVYbgLkAkDBTk6WxJKWYIS9YpiydMUtzyYUNDXpPD6xZu0IRzmZaYd7MtCyjVWCqzbCGeD/ZuXDUSYvMq+Jr66L6Bx2bjLCjalyVivtPHl3Uzp5Uayx0wiVTCFEQ6XSbwfLddtvpHVHfBmLwHgiX3HR8hTgCQ==" Feb 08 12:43:12 volumio go-librespot[25083]: time="2026-02-08T12:43:12-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:13 volumio go-librespot[25083]: time="2026-02-08T12:43:13-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:13 volumio go-librespot[25083]: time="2026-02-08T12:43:13-06:00" level=debug msg="completed challenge" Feb 08 12:43:13 volumio go-librespot[25083]: time="2026-02-08T12:43:13-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 08 12:43:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:13 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:13 volumio sudo[25106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:13 volumio sudo[25106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:13 volumio sudo[25106]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:13 volumio volumio[24692]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 08 12:43:13 volumio sudo[25110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 08 12:43:13 volumio sudo[25110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:13 volumio sudo[25110]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:13 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 08 12:43:13 volumio sudo[25113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 08 12:43:13 volumio sudo[25113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:13 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 12:43:13 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 12:43:13 volumio sudo[25113]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:14 volumio volumio[24692]: info: Initializing connection to go-librespot Websocket Feb 08 12:43:14 volumio volumio[24692]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:43:14 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:43:14 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:14 volumio sudo[25121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:14 volumio sudo[25121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:14 volumio sudo[25121]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:14 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:14 volumio sudo[25124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:14 volumio sudo[25124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:14 volumio sudo[25124]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:15 volumio mpd[25075]: 2026-02-08T12:43:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 12:43:15 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:15 volumio sudo[25127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:15 volumio sudo[25127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:15 volumio sudo[25127]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 12:43:15 volumio sudo[25002]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:15 volumio sudo[24920]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:15 volumio sudo[24910]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:15 volumio volumio[24692]: error: MPD error: The expression evaluated to a falsy value: Feb 08 12:43:15 volumio volumio[24692]: assert.ok(self.idling) Feb 08 12:43:15 volumio volumio[24692]: error: The expression evaluated to a falsy value: Feb 08 12:43:15 volumio volumio[24692]: assert.ok(self.idling) Feb 08 12:43:15 volumio volumio[24692]: error: MPD error: The expression evaluated to a falsy value: Feb 08 12:43:15 volumio volumio[24692]: assert.ok(self.idling) Feb 08 12:43:15 volumio volumio[24692]: error: The expression evaluated to a falsy value: Feb 08 12:43:15 volumio volumio[24692]: assert.ok(self.idling) Feb 08 12:43:15 volumio volumio[24692]: info: MPD running with PID25075 Feb 08 12:43:15 volumio volumio[24692]: ,establishing connection Feb 08 12:43:15 volumio volumio[24692]: error: updateQueue error: null Feb 08 12:43:15 volumio volumio[24692]: error: updateQueue error: null Feb 08 12:43:16 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:16 volumio sudo[25134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:16 volumio sudo[25134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:16 volumio sudo[25134]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78. Feb 08 12:43:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:16 volumio go-librespot[25136]: go-librespot daemon starting... Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="app state loaded" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=info msg="zeroconf server listening on port 45275" Feb 08 12:43:16 volumio volumio[24692]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:16 volumio sudo[25145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:16 volumio sudo[25145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="obtained new client token: AAAFbwywTWE6b8CKMlBGXLvHRUHXGN/p6+3LmO7RWYIMb5nTeU0cvfRwXKbxVNdv/xiRRliRkjYImBL2DyHYelJXRaTOfKqf8NrxijbshfMG8a8vjtiMVUR7Wf5ohd3SbAnnoTbr5NfN3sCTNvpQlX/RCrdN4VwJx99B/eKSti47rRKFWM5GIeGaEITuXNY92KS/QtN1AL01u/bxAotKywahmObb3E0AwqkQyBpKqGo6V6A4IYfPKJQHAw==" Feb 08 12:43:16 volumio sudo[25145]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:16 volumio volumio[24692]: info: Completed starting Core Plugins Feb 08 12:43:16 volumio volumio[24692]: info: ------------------------------------------- Feb 08 12:43:16 volumio volumio[24692]: info: ----- MyVolumio plugins startup ---- Feb 08 12:43:16 volumio volumio[24692]: info: ------------------------------------------- Feb 08 12:43:16 volumio volumio[24692]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=debug msg="completed challenge" Feb 08 12:43:16 volumio go-librespot[25137]: time="2026-02-08T12:43:16-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:17 volumio volumio[24692]: info: Initializing connection to go-librespot Websocket Feb 08 12:43:17 volumio volumio[24692]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:43:18 volumio sudo[25148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:43:18 volumio sudo[25148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:18 volumio sudo[25150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:43:18 volumio sudo[25150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:18 volumio sudo[25152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:43:18 volumio sudo[25152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:18 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 08 12:43:18 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 08 12:43:18 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 08 12:43:18 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 12:43:18 volumio sudo[25150]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:18 volumio sudo[25148]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:18 volumio sudo[25152]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:18 volumio mpd_monitor.sh[25156]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 12:43:18 volumio volumio[24692]: info: Successfully started MPD Monitor Feb 08 12:43:18 volumio volumio[24692]: info: Successfully started MPD Monitor Feb 08 12:43:18 volumio volumio[24692]: info: Successfully started MPD Monitor Feb 08 12:43:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79. Feb 08 12:43:20 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:20 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:20 volumio go-librespot[25161]: go-librespot daemon starting... Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="app state loaded" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:20 volumio volumio[24692]: info: Initializing connection to go-librespot Websocket Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="new websocket client" Feb 08 12:43:20 volumio volumio[24692]: info: Connection to go-librespot Websocket established Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-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 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-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 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=info msg="zeroconf server listening on port 36901" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="obtained new client token: AAD1H8nIGFtcN2Pt3VLarC0af9/UpsqKRSjWkMUkbLvrSsUaCiAFR0zzdJbtFxIsp+cE81yvXmwaQRVbFzoh3tZbWx9HIy6PwKIAG0X9l9jp09Qcv/w7ioArs93KnDjeI/Iytgvj8yoZB5KSYrO5soQCQVrig/EAXckKXds2N4zsLMdIL7UwlF9riUvPmEx58WA9O4cT2o+74xCChsuAf2KaEcIO+nQE4Nuvh+NnA6jwLWCcpdXayFffgg==" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=debug msg="completed challenge" Feb 08 12:43:20 volumio go-librespot[25162]: time="2026-02-08T12:43:20-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:20 volumio volumio[24692]: info: Connection to go-librespot Websocket closed Feb 08 12:43:22 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:43:23 volumio volumio[24692]: info: Getting Spotify volume Feb 08 12:43:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80. Feb 08 12:43:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:23 volumio go-librespot[25184]: go-librespot daemon starting... Feb 08 12:43:23 volumio go-librespot[25185]: time="2026-02-08T12:43:23-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:23 volumio go-librespot[25185]: time="2026-02-08T12:43:23-06:00" level=debug msg="app state loaded" Feb 08 12:43:23 volumio go-librespot[25185]: time="2026-02-08T12:43:23-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:23 volumio go-librespot[25185]: time="2026-02-08T12:43:23-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-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 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-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 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-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 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-06:00" level=info msg="zeroconf server listening on port 42913" Feb 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-06:00" level=debug msg="obtained new client token: AAAEbnZEA2fVy6rM8QseM30Dj1zfr13yoTj/MwXRJxVHyYrD2PeKLOdTnSXeMne2xriCqtsQz5qkCNiTrPWkfd3pJQnjehm5RxpYRJekssK8AYy+xtEcDZBjBACZlIQlJ9R7lC+BKPIUQC+lSXxc6vkm4d5lhw6izIf51ON78aLtFmpeZzS1awGfRdrMQPVpYfyJoEbfXpP2Pga/B/4t1RuZ47ePMVMtMGp/T66WwDewigNgYeACI232lw==" Feb 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Feb 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 08 12:43:24 volumio go-librespot[25185]: time="2026-02-08T12:43:24-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:25 volumio go-librespot[25185]: time="2026-02-08T12:43:24-06:00" level=debug msg="completed challenge" Feb 08 12:43:25 volumio go-librespot[25185]: time="2026-02-08T12: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 08 12:43:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:25 volumio volumio[24692]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 12:43:25 volumio volumio[24692]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:43:25 volumio volumio[24692]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 08 12:43:25 volumio volumio[24692]: errno: -111, Feb 08 12:43:25 volumio volumio[24692]: code: 'ECONNREFUSED', Feb 08 12:43:25 volumio volumio[24692]: syscall: 'connect', Feb 08 12:43:25 volumio volumio[24692]: address: '127.0.0.1', Feb 08 12:43:25 volumio volumio[24692]: port: 9879, Feb 08 12:43:25 volumio volumio[24692]: response: undefined Feb 08 12:43:25 volumio volumio[24692]: } Feb 08 12:43:25 volumio volumio[24692]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 12:43:25 volumio sudo[25206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 12:42' Feb 08 12:43:25 volumio sudo[25206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:26 volumio sudo[25206]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:26 volumio volumio-remote-updater[751]: [2026-02-08 12:43:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 12:43:26 volumio volumio-remote-updater[751]: [2026-02-08 12:43:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 12:43:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 12:43:26 volumio systemd[1]: volumio.service: Consumed 50.784s CPU time. Feb 08 12:43:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 12:43:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 12:43:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1142. Feb 08 12:43:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 12:43:26 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 12:43:26 volumio systemd[1]: volumio.service: Consumed 50.784s CPU time. Feb 08 12:43:26 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 12:43:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 12:43:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81. Feb 08 12:43:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:28 volumio go-librespot[25234]: go-librespot daemon starting... Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=debug msg="app state loaded" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:28 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:28 volumio volumio[25219]: info: ----- Volumio3 ---- Feb 08 12:43:28 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:28 volumio volumio[25219]: info: ----- System startup ---- Feb 08 12:43:28 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-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 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-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 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=info msg="zeroconf server listening on port 44623" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=debug msg="obtained new client token: AADpeADdJMlawxR4rCoThNkz0NwiDRcmcgqgzXEW7AZYilYguyH/af4BhZdbMWWYyMrX8PZOoJOtqKlIyKnsl86cjOQHQcCPWYpRju+jiSFXloJuvYy3zSrcMx1gP/dg+Hqog3zBu+CXQEdGqKjNYuCTm5D1MZp4a8CvWpRiztpAQ7EXy37rv85lXxeJppIGOWmCHneBcWEqBx7xr7xsJtZsQZWWrQA30uoJSDpl1rd28MhN6qkSbnWXAQ==" Feb 08 12:43:28 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-06:00" level=debug msg="completed challenge" Feb 08 12:43:28 volumio go-librespot[25235]: time="2026-02-08T12:43:28-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 08 12:43:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:29 volumio volumio[25219]: info: MYVOLUMIO Environment detected Feb 08 12:43:29 volumio volumio[25219]: info: Plugin folders cleanup Feb 08 12:43:29 volumio volumio[25219]: info: Scanning into folder /volumio/app/plugins/ Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category audio_interface Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category miscellanea Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category music_service Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category plugins.json Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category system_controller Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category user_interface Feb 08 12:43:29 volumio volumio[25219]: info: Scanning into folder /data/plugins/ Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category audio_interface Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category music_service Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category system_controller Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category system_hardware Feb 08 12:43:29 volumio volumio[25219]: info: Scanning category user_interface Feb 08 12:43:29 volumio volumio[25219]: info: Plugin folders cleanup completed Feb 08 12:43:29 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:29 volumio volumio[25219]: info: ----- Core plugins startup ---- Feb 08 12:43:29 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:29 volumio volumio[25219]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 12:43:29 volumio volumio[25219]: info: Adding plugin upnp to MyMusic Plugins Feb 08 12:43:29 volumio volumio[25219]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 12:43:29 volumio volumio[25219]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 12:43:29 volumio volumio[25219]: info: Loading plugins from folder /data/plugins/ Feb 08 12:43:29 volumio volumio[25219]: info: Loading plugin "system"... Feb 08 12:43:29 volumio volumio[25219]: info: Loading plugin "appearance"... Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "network"... Feb 08 12:43:30 volumio volumio[25219]: info: Refreshing Cached IP Addresses Feb 08 12:43:30 volumio sudo[25256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 12:43:30 volumio sudo[25256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:30 volumio sudo[25256]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:30 volumio sudo[25258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 12:43:30 volumio sudo[25258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "services"... Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "alsa_controller"... Feb 08 12:43:30 volumio sudo[25258]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:30 volumio sudo[25266]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 12:43:30 volumio sudo[25266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:30 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "wizard"... Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "networkfs"... Feb 08 12:43:30 volumio volumio[25219]: info: Starting Udev Watcher for removable devices Feb 08 12:43:30 volumio volumio[25219]: info: Ignoring mount for partition: boot Feb 08 12:43:30 volumio volumio[25219]: info: Ignoring mount for partition: volumio Feb 08 12:43:30 volumio volumio[25219]: info: Ignoring mount for partition: volumio_data Feb 08 12:43:30 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:43:30 volumio volumio[25219]: info: Mounting Device Wikipedia Feb 08 12:43:30 volumio sudo[25295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 08 12:43:30 volumio sudo[25295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:30 volumio sudo[25295]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:30 volumio volumio[25219]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 12:43:30 volumio volumio[25219]: dmesg(1) may have more information after failed mount system call. Feb 08 12:43:30 volumio volumio[25219]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 08 12:43:30 volumio volumio[25219]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 12:43:30 volumio volumio[25219]: dmesg(1) may have more information after failed mount system call. Feb 08 12:43:30 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "volumio_command_line_client"... Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "upnp"... Feb 08 12:43:30 volumio volumio[25219]: info: [1770576210972] Starting Upmpd Daemon Feb 08 12:43:30 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "my_music"... Feb 08 12:43:30 volumio volumio[25219]: info: Loading plugin "mpd"... Feb 08 12:43:31 volumio sudo[25266]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:31 volumio volumio-remote-updater[751]: [2026-02-08 12:43:31] [connect] Successful connection Feb 08 12:43:31 volumio volumio[25219]: info: Loading plugin "upnp_browser"... Feb 08 12:43:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82. Feb 08 12:43:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:32 volumio go-librespot[25297]: go-librespot daemon starting... Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=debug msg="app state loaded" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12: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 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12: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 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12: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 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=info msg="zeroconf server listening on port 33979" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=debug msg="obtained new client token: AABz6ETccIwzwondplgM+ACr9sC5iPMbDmUv+na0dBBN8AcoGamOqzGmWfVygK+jmCZnsLXaJnIFLcaxbebY9IFf1BtlMv8M4J5g0vkIx8z+YK+gg9fp/BZWyrjxTRiHf1QnHKgoSzYDLkA+ypxcP0oZrtx2Hnmq2gTSnYQ1/lHt3F49sH+FUh3CUBnPj9jARw6k7baS+9QYwhKStrWt91PClpDWM4B1WRPztxM7L5K8tR3duMVvjJnkZQ==" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=debug msg="completed challenge" Feb 08 12:43:32 volumio go-librespot[25298]: time="2026-02-08T12:43:32-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "alarm-clock"... Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "airplay_emulation"... Feb 08 12:43:34 volumio volumio[25219]: info: Starting Shairport Sync Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "last_100"... Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "webradio"... Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "i2s_dacs"... Feb 08 12:43:34 volumio volumio[25219]: info: I2S DAC not set, start Auto-detection Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "volumiodiscovery"... Feb 08 12:43:34 volumio volumio[25219]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 12:43:34 volumio volumio[25219]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:43:34 volumio volumio[25219]: *** WARNING *** For more information see Feb 08 12:43:34 volumio volumio[25219]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 12:43:34 volumio volumio[25219]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:43:34 volumio volumio[25219]: *** WARNING *** For more information see Feb 08 12:43:34 volumio node[25219]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 12:43:34 volumio node[25219]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:43:34 volumio node[25219]: *** WARNING *** For more information see Feb 08 12:43:34 volumio node[25219]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 12:43:34 volumio node[25219]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:43:34 volumio node[25219]: *** WARNING *** For more information see Feb 08 12:43:34 volumio volumio[25219]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 12:43:34 volumio volumio[25219]: info: Discovery: Started advertising with name: Volumio Feb 08 12:43:34 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 12:43:34 volumio volumio[25219]: info: Loading plugin "bandcamp"... Feb 08 12:43:35 volumio volumio[25219]: info: Plugin calmradio is not enabled Feb 08 12:43:35 volumio volumio[25219]: info: Loading plugin "soundcloud"... Feb 08 12:43:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83. Feb 08 12:43:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:36 volumio go-librespot[25307]: go-librespot daemon starting... Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=debug msg="app state loaded" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:36 volumio volumio[25219]: info: Loading plugin "spop"... Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12: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 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12: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 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12: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 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=info msg="zeroconf server listening on port 35869" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=debug msg="obtained new client token: AAARUL35FP3k2NNr/9IHkHIHt9sUGj+IpG50Qoph8hjQWIdOgYgVhbP88UuPA6p6a4w93FNpdJmMsfzLyEtaT7ihfMy/40ND14WLwv0zP1+7d8p3tQaE1wgX0A7SLQwXNI28JZbVL+ujbMb/J/s1OFzZKHeaw6kDf2t9El9RtctfeE56z6+h8QZmOPjy5ZU0TjWDARYjWGPy5y3ThiUvosIOF90/DwRgMWvleWsVwM2SlDfq1ItdbBY1fg==" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12:43:36-06:00" level=debug msg="completed challenge" Feb 08 12:43:36 volumio go-librespot[25308]: time="2026-02-08T12: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 08 12:43:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:37 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:43:37 volumio volumio[25219]: info: Loading plugin "squeezelite_mc"... Feb 08 12:43:37 volumio volumio[25219]: info: Loading plugin "youtube2"... Feb 08 12:43:38 volumio volumio[25219]: info: Loading plugin "ytcr"... Feb 08 12:43:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84. Feb 08 12:43:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:39 volumio go-librespot[25321]: go-librespot daemon starting... Feb 08 12:43:39 volumio go-librespot[25322]: time="2026-02-08T12:43:39-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:39 volumio go-librespot[25322]: time="2026-02-08T12:43:39-06:00" level=debug msg="app state loaded" Feb 08 12:43:39 volumio go-librespot[25322]: time="2026-02-08T12:43:39-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:39 volumio go-librespot[25322]: time="2026-02-08T12:43:39-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:39 volumio volumio[25219]: info: Loading plugin "ytmusic"... Feb 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12: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 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12: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 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12: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 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12:43:40-06:00" level=info msg="zeroconf server listening on port 41253" Feb 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12:43:40-06:00" level=debug msg="obtained new client token: AAA4JzcdFWnIbQMkgZjqrXot1BTrd95vzOV760Eg+LEaN5jbV83fhjAqSm8I7YBakI1aKITyQEiS7Eor/1NP/zfx9CowZI530Nmv9KVhQptp1y1+9Umr/7yc3/CwMXpUFh2RCfEgOn6tWMk8/662gq9E8P1Np08cP2InqTQ//eWTcdc5IjWb1mcM821hCQTLDxeRtv7HiSQ6IPQ7uSAHwstbgsXfqDZBtjCWBZEMVQHs5UAI/SKjfaI=" Feb 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12:43:40-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12:43:40-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12:43:40-06:00" level=debug msg="completed challenge" Feb 08 12:43:40 volumio go-librespot[25322]: time="2026-02-08T12: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 08 12:43:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:40 volumio volumio[25219]: info: Plugin now_playing is not enabled Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "outputs"... Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "albumart"... Feb 08 12:43:40 volumio volumio[25219]: info: Plugin example_plugin is not enabled Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "inputs"... Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "updater_comm"... Feb 08 12:43:40 volumio volumio[25219]: info: Plugin mpdemulation is not enabled Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "rest_api"... Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "websocket"... Feb 08 12:43:40 volumio volumio[25219]: info: Starting Socket.io Server version 1.7.4 Feb 08 12:43:40 volumio volumio[25219]: info: Plugin fusiondsp is not enabled Feb 08 12:43:40 volumio volumio[25219]: info: Plugin mpdoutput is not enabled Feb 08 12:43:40 volumio volumio[25219]: info: Plugin RoonBridge is not enabled Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "podcast"... Feb 08 12:43:40 volumio volumio[25219]: info: ControllerPodcast::constructor Feb 08 12:43:40 volumio volumio[25219]: info: Loading plugin "volusonic"... Feb 08 12:43:41 volumio volumio[25330]: Forking 3 albumart workers Feb 08 12:43:43 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:43:43 volumio volumio[25219]: info: Applying required configuration parameters for plugin volusonic Feb 08 12:43:43 volumio volumio[25219]: info: Loading plugin "backup_restore"... Feb 08 12:43:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85. Feb 08 12:43:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:43 volumio go-librespot[25387]: go-librespot daemon starting... Feb 08 12:43:43 volumio volumio[25356]: Starting albumart workers Feb 08 12:43:43 volumio volumio[25354]: Starting albumart workers Feb 08 12:43:43 volumio volumio[25355]: Starting albumart workers Feb 08 12:43:43 volumio go-librespot[25388]: time="2026-02-08T12:43:43-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:43 volumio go-librespot[25388]: time="2026-02-08T12:43:43-06:00" level=debug msg="app state loaded" Feb 08 12:43:43 volumio go-librespot[25388]: time="2026-02-08T12:43:43-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:43 volumio go-librespot[25388]: time="2026-02-08T12:43:43-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:43 volumio volumio-remote-updater[751]: [2026-02-08 12:43:43] [connect] Successful connection Feb 08 12:43:43 volumio volumio[25219]: info: Applying required configuration parameters for plugin backup_restore Feb 08 12:43:43 volumio volumio[25219]: info: Plugin rpi_eeprom_config is not enabled Feb 08 12:43:43 volumio volumio[25219]: info: Plugin rpi_eeprom_updater is not enabled Feb 08 12:43:43 volumio volumio[25219]: info: Loading plugin "scheduledrestart"... Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-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 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-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 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-06:00" level=info msg="zeroconf server listening on port 43207" Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-06:00" level=debug msg="obtained new client token: AAAVTkf+IwBn0fsYu046CsSuMSmtAeRIVP4hRxRSxLVfnP4uW5xrfX2i5Qw0523T/ZcCwSCF6HYWa2QF2j0oj55NUKX8m84cC8n2TJTHvKAUZosGpVodTH39ks9U7bch54ccfXuCFyBxjd2fobJ7nY+B52nL8L9QuDiwksfsioyg2JksvPEOPePCNDHWDyQYUOsuz7FUK8heK+6ySMZxX129jhYOQ8XH+FRhkY6zoZgRqUrf1RJq6yG6wQ==" Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12:43:44-06:00" level=debug msg="completed challenge" Feb 08 12:43:44 volumio go-librespot[25388]: time="2026-02-08T12: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 08 12:43:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:44 volumio volumio[25219]: info: Applying required configuration parameters for plugin scheduledrestart Feb 08 12:43:44 volumio volumio[25219]: info: Plugin Bluetoothremote is not enabled Feb 08 12:43:44 volumio volumio[25219]: info: Plugin music_services_shield is not enabled Feb 08 12:43:44 volumio volumio[25219]: info: Loading plugin "Systeminfo"... Feb 08 12:43:45 volumio volumio[25219]: info: Plugin peppymeterbasic is not enabled Feb 08 12:43:45 volumio volumio[25219]: info: Loading plugin "peppyspectrum"... Feb 08 12:43:45 volumio volumio[25219]: info: Loading i18n strings for locale en Feb 08 12:43:45 volumio volumio[25219]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 12:43:45 volumio volumio[25219]: Updating browse sources language Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::initPlayerControls Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:45 volumio volumio[25219]: Express server listening on port 3000 Feb 08 12:43:45 volumio volumio[25219]: [Metrics] WebUI: 17s 560.41ms Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::resetVolumioState Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::getcurrentVolume Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:45 volumio sudo[25418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 12:43:45 volumio sudo[25418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:45 volumio sudo[25418]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:45 volumio sudo[25420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 12:43:45 volumio sudo[25420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:45 volumio sudo[25420]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:45 volumio volumio[25219]: info: Volumio Network Manager: Network status updated: 1 Feb 08 12:43:45 volumio volumio[25219]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::pushState Feb 08 12:43:45 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::updateTrackBlock Feb 08 12:43:45 volumio volumio[25219]: info: CorePlayQueue::getTrackBlock Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:43:45 volumio volumio-remote-updater[751]: [2026-02-08 12:43:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770576223 101 Feb 08 12:43:45 volumio volumio[25219]: 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 08 12:43:45 volumio volumio[25219]: info: Reloading queue from file Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::setRepeat null single undefined Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::pushState Feb 08 12:43:45 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::setRandom null Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::pushState Feb 08 12:43:45 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:45 volumio volumio[25219]: info: Setting Device type: Raspberry PI Feb 08 12:43:45 volumio volumio[25219]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:43:45 volumio volumio[25219]: info: CoreStateMachine::pushState Feb 08 12:43:45 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:45 volumio volumio[25219]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:45 volumio volumio[25219]: info: Completed loading Core Plugins Feb 08 12:43:45 volumio volumio[25219]: info: Preparing to generate the ALSA configuration file Feb 08 12:43:45 volumio sudo[25434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 12:43:45 volumio sudo[25434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:46 volumio volumio[25219]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 12:43:46 volumio volumio[25219]: info: Discovery: Found device Volumio Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioGetState Feb 08 12:43:46 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:46 volumio volumio[25219]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 12:43:46 volumio volumio[25219]: info: Discovery: Found device Volumio Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioGetState Feb 08 12:43:46 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:46 volumio volumio[25219]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 12:43:46 volumio volumio[25219]: info: Reading ALSA contributions from plugins. Feb 08 12:43:46 volumio sudo[25434]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:46 volumio volumio[25219]: info: Asound.conf file unchanged, so no further update is needed Feb 08 12:43:46 volumio volumio[25219]: info: Output device has changed, restarting MPD Feb 08 12:43:46 volumio volumio[25219]: info: Output device has changed, restarting Shairport Sync Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:46 volumio sudo[25437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:43:46 volumio sudo[25437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:46 volumio sudo[25437]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:46 volumio sudo[25439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:43:46 volumio sudo[25439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:46 volumio volumio[25219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:46 volumio volumio[25219]: info: ___________ START PLUGINS ___________ Feb 08 12:43:46 volumio volumio[25219]: info: ControllerMpd::onStart: Initializing MPD Feb 08 12:43:46 volumio volumio[25219]: info: Creating MPD Configuration file Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226127] CoreMusicLibrary::Adding element Media Servers Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 12:43:46 volumio sudo[25447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:43:46 volumio sudo[25447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:46 volumio sudo[25447]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:46 volumio sudo[25449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:43:46 volumio sudo[25449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:46 volumio volumio[25219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:46 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 12:43:46 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 12:43:46 volumio systemd[1]: mpd.service: Consumed 4.276s CPU time. Feb 08 12:43:46 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 12:43:46 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 12:43:46 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 12:43:46 volumio volumio[25219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226247] CoreMusicLibrary::Adding element Last_100 Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226251] CoreMusicLibrary::Adding element Webradio Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:43:46 volumio volumio[25219]: info: Initializing BBC Radios Feb 08 12:43:46 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 12:43:46 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226331] CoreMusicLibrary::Adding element Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226342] CoreMusicLibrary::Adding element SoundCloud Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: info: Creating Spotify config file Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:46 volumio sudo[25469]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 12:43:46 volumio sudo[25469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 12:43:46 volumio sudo[25469]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:46 volumio volumio[25219]: info: [squeezelite_mc] Starting proxy server... Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226705] CoreMusicLibrary::Adding element YouTube2 Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226724] CoreMusicLibrary::Adding element YouTube Music Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube Music Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226736] CoreMusicLibrary::Adding element Podcast Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube Music Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Podcast Feb 08 12:43:46 volumio volumio[25219]: info: Loading i18n strings for locale en Feb 08 12:43:46 volumio volumio[25219]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 12:43:46 volumio volumio[25219]: Updating browse sources language Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube Music Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Podcast Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube Music Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Podcast Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:46 volumio volumio[25219]: info: [1770576226794] CoreMusicLibrary::Adding element Volusonic Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source YouTube Music Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Podcast Feb 08 12:43:46 volumio volumio[25219]: Cannot find translation for source Volusonic Feb 08 12:43:46 volumio volumio[25219]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 08 12:43:46 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 12:43:46 volumio volumio[25219]: info: Volumio Calling Home Feb 08 12:43:46 volumio volumio[25219]: info: [squeezelite_mc] Proxy server started on port 45183 Feb 08 12:43:46 volumio volumio[25219]: info: Preparing to generate the ALSA configuration file Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:43:47 volumio volumio[25219]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 12:43:47 volumio volumio[25219]: info: Reading ALSA contributions from plugins. Feb 08 12:43:47 volumio volumio[25219]: info: MPD Permissions set Feb 08 12:43:47 volumio volumio[25219]: info: MPD Permissions set Feb 08 12:43:47 volumio volumio[25219]: info: Upmpdcli Daemon Started Feb 08 12:43:47 volumio volumio[25219]: info: Spotify config file written Feb 08 12:43:47 volumio sudo[25484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 12:43:47 volumio sudo[25484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio volumio[25219]: 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 08 12:43:47 volumio volumio[25219]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:43:47 volumio volumio[25219]: info: CoreStateMachine::pushState Feb 08 12:43:47 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::volumioPushState Feb 08 12:43:47 volumio volumio[25219]: info: [squeezelite_mc] Server discovery started Feb 08 12:43:47 volumio volumio[25219]: info: [squeezelite_mc] Player finder started Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 08 12:43:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: No need to fix Spotify hosts Feb 08 12:43:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:47 volumio go-librespot[25488]: go-librespot daemon starting... Feb 08 12:43:47 volumio sudo[25484]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="app state loaded" Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:47 volumio volumio[25219]: 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 08 12:43:47 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:43:47 volumio volumio[25219]: info: Volumio called home Feb 08 12:43:47 volumio volumio[25219]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 08 12:43:47 volumio volumio[25219]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 08 12:43:47 volumio volumio[25219]: 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 08 12:43:47 volumio volumio[25219]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 12:43:47 volumio volumio[25219]: SPOTIFY: BQDRS3Jeyu16DbL-ID5wJ_WRv2XfCC2vvWaG92LkbqwCVlQCkndygakZBW41lnwX5aCQELmhexcQpoSyPq70Byv5I14j4RsTGG6zPLtwZbXryiIZSVRZFp6rRWZBTONHimVwO56qXhq5YRSSYNEnpGbtkIr_VmzVrrGnuMS2V0Yv74g2gAIYWpTGx59Skkz4VFdZB1l_iTk3T4XdxEHRG6RRdcRRUZgSx_gwUIU Feb 08 12:43:47 volumio volumio[25219]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 12:43:47 volumio volumio[25219]: info: New Spotify access token = BQDRS3Jeyu16DbL-ID5wJ_WRv2XfCC2vvWaG92LkbqwCVlQCkndygakZBW41lnwX5aCQELmhexcQpoSyPq70Byv5I14j4RsTGG6zPLtwZbXryiIZSVRZFp6rRWZBTONHimVwO56qXhq5YRSSYNEnpGbtkIr_VmzVrrGnuMS2V0Yv74g2gAIYWpTGx59Skkz4VFdZB1l_iTk3T4XdxEHRG6RRdcRRUZgSx_gwUIU Feb 08 12:43:47 volumio volumio[25219]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 12:43:47 volumio sudo[25510]: 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 08 12:43:47 volumio sudo[25510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:43:47 volumio sudo[25510]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=info msg="zeroconf server listening on port 35277" Feb 08 12:43:47 volumio volumio[25219]: info: Starting Shairport Sync Feb 08 12:43:47 volumio volumio[25219]: info: Starting Shairport Sync Feb 08 12:43:47 volumio volumio[25219]: info: Starting Shairport Sync Feb 08 12:43:47 volumio sudo[25513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:47 volumio sudo[25513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio volumio[25219]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 08 12:43:47 volumio sudo[25515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:47 volumio sudo[25517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:47 volumio sudo[25515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio sudo[25517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="obtained new client token: AADMY5PovKAwGPOzNGtjQ3X8OGVwsOP1FyBPAnuNa+ByLcViO4o/DU8Y20bg0pPPPcybL402QKB/r9xBBMvOdA1xHMau13o4BGJS3TZoQuQjbDUfnGAAAJmBhoXRwQ/BO8VrUiWyRLQrco5kVer41GhQoDLwmsf0cbd8HJBE5B6EzCvWF8ZyBFd7jyMmxJDQCQ7mUtcH7W5AVTdK23N3kAVg5ZwWJdxnd4qz9TUsvigc+EMX7yfhOulF5g==" Feb 08 12:43:47 volumio sudo[25521]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 08 12:43:47 volumio sudo[25521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio volumio[25219]: info: Asound.conf file unchanged, so no further update is needed Feb 08 12:43:47 volumio volumio[25219]: info: Output device has changed, restarting MPD Feb 08 12:43:47 volumio volumio[25219]: info: Output device has changed, restarting Shairport Sync Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:47 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:47 volumio sudo[25526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:43:47 volumio sudo[25528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:43:47 volumio sudo[25528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio sudo[25526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:43:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:43:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:47 volumio systemd[1]: shairport-sync.service: Consumed 2.018s CPU time. Feb 08 12:43:47 volumio sudo[25526]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio sudo[25521]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-06:00" level=debug msg="completed challenge" Feb 08 12:43:47 volumio volumio[25219]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:43:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:47 volumio sudo[25513]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio go-librespot[25499]: time="2026-02-08T12:43:47-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 08 12:43:47 volumio sudo[25515]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:47 volumio sudo[25517]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:48 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 12:43:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 12:43:48 volumio systemd[1]: mpd.service: Consumed 1.564s CPU time. Feb 08 12:43:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 12:43:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 12:43:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 12:43:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 12:43:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 12:43:48 volumio volumio[25219]: info: MPD Permissions set Feb 08 12:43:48 volumio volumio[25219]: info: Shairport-Sync Started Feb 08 12:43:48 volumio volumio[25219]: Error adding Membership: Error: addMembership EINVAL Feb 08 12:43:48 volumio volumio[25219]: info: Shairport-Sync Started Feb 08 12:43:48 volumio volumio[25219]: info: Shairport-Sync Started Feb 08 12:43:48 volumio volumio[25219]: 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 08 12:43:48 volumio sudo[25558]: 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 08 12:43:48 volumio sudo[25558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:48 volumio sudo[25558]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:48 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:48 volumio sudo[25561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 08 12:43:48 volumio sudo[25561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:48 volumio systemd[1]: Reloading. Feb 08 12:43:48 volumio sudo[25555]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 12:43:48 volumio sudo[25555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 12:43:48 volumio sudo[25555]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::volumioGetState Feb 08 12:43:48 volumio volumio[25219]: info: CorePlayQueue::getTrack 0 Feb 08 12:43:48 volumio volumio[25219]: info: Starting Shairport Sync Feb 08 12:43:48 volumio sudo[25570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:43:48 volumio sudo[25570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:48 volumio volumio[25219]: 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 08 12:43:48 volumio volumio[25219]: info: Spotify Successfully logged in Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:43:48 volumio volumio[25219]: info: [1770576228662] CoreMusicLibrary::Adding element Spotify Feb 08 12:43:48 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source Bandcamp Discover Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source SoundCloud Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source YouTube2 Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source YouTube Music Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source Podcast Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source Volusonic Feb 08 12:43:48 volumio volumio[25219]: Cannot find translation for source Spotify Feb 08 12:43:49 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:49 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:49 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:43:49 volumio volumio[25219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:43:49 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:43:50 volumio volumio[25219]: info: go-librespot daemon successfully initialized Feb 08 12:43:50 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 08 12:43:50 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 08 12:43:51 volumio sudo[25561]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:51 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:51 volumio sudo[25605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:51 volumio sudo[25605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86. Feb 08 12:43:51 volumio sudo[25605]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:51 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 08 12:43:51 volumio sudo[25609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 08 12:43:51 volumio sudo[25609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:51 volumio go-librespot[25607]: go-librespot daemon starting... Feb 08 12:43:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:43:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:43:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:51 volumio systemd[1]: shairport-sync.service: Consumed 1.600s CPU time. Feb 08 12:43:51 volumio go-librespot[25611]: time="2026-02-08T12:43:51-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:51 volumio go-librespot[25611]: time="2026-02-08T12:43:51-06:00" level=debug msg="app state loaded" Feb 08 12:43:51 volumio go-librespot[25611]: time="2026-02-08T12:43:51-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:51 volumio go-librespot[25611]: time="2026-02-08T12:43:51-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:43:51 volumio sudo[25570]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:51 volumio volumio[25219]: info: Shairport-Sync Started Feb 08 12:43:51 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 12:43:52 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 08 12:43:52 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 12:43:52 volumio sudo[25609]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=info msg="zeroconf server listening on port 34299" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="obtained new client token: AAARMthg3fmf0dLJOZ4/jVrgiaig1QIbMqq/1oZvz67H9EK6Pz0Ztv4ArqwrkCzzJPh4GkNqDxnzC/8L1R8tmkR5SPWoDeMFPFf5A4/7JwebpLUhDC+pBrhUon1Vy60gOhfty93XFhBlEG35iv0p3xj7R+wp4JpOINrrfj6FTJFP6hjFx3YDNa6Im8WIwWiw1vK6sD33yI9QQOkhn/n3Txhf61LeT6ONIGvIMQh/P4+H9m02qrLU/Dp49A==" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=debug msg="completed challenge" Feb 08 12:43:52 volumio go-librespot[25611]: time="2026-02-08T12:43:52-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:52 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:52 volumio sudo[25636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:52 volumio sudo[25636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:52 volumio sudo[25636]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:52 volumio volumio[25219]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 08 12:43:52 volumio sudo[25640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 08 12:43:52 volumio sudo[25640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:52 volumio sudo[25640]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:52 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 08 12:43:52 volumio sudo[25643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 08 12:43:52 volumio sudo[25643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:53 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 12:43:53 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 12:43:53 volumio sudo[25643]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:53 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:53 volumio sudo[25650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:53 volumio sudo[25650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:53 volumio volumio[25219]: info: Initializing connection to go-librespot Websocket Feb 08 12:43:53 volumio volumio[25219]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:43:53 volumio sudo[25650]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:54 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:54 volumio sudo[25653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:54 volumio sudo[25653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:54 volumio sudo[25653]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:54 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:54 volumio sudo[25656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:54 volumio sudo[25656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:54 volumio sudo[25656]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:54 volumio mpd[25603]: 2026-02-08T12:43:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 12:43:55 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 12:43:55 volumio sudo[25528]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:55 volumio sudo[25439]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:55 volumio sudo[25449]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:55 volumio volumio[25219]: error: MPD error: The expression evaluated to a falsy value: Feb 08 12:43:55 volumio volumio[25219]: assert.ok(self.idling) Feb 08 12:43:55 volumio volumio[25219]: error: The expression evaluated to a falsy value: Feb 08 12:43:55 volumio volumio[25219]: assert.ok(self.idling) Feb 08 12:43:55 volumio volumio[25219]: error: MPD error: The expression evaluated to a falsy value: Feb 08 12:43:55 volumio volumio[25219]: assert.ok(self.idling) Feb 08 12:43:55 volumio volumio[25219]: error: The expression evaluated to a falsy value: Feb 08 12:43:55 volumio volumio[25219]: assert.ok(self.idling) Feb 08 12:43:55 volumio volumio[25219]: info: MPD running with PID25603 Feb 08 12:43:55 volumio volumio[25219]: ,establishing connection Feb 08 12:43:55 volumio volumio[25219]: error: updateQueue error: null Feb 08 12:43:55 volumio volumio[25219]: error: updateQueue error: null Feb 08 12:43:55 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:55 volumio sudo[25663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:55 volumio sudo[25663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:55 volumio sudo[25663]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87. Feb 08 12:43:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:55 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:43:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:55 volumio go-librespot[25665]: go-librespot daemon starting... Feb 08 12:43:55 volumio go-librespot[25667]: time="2026-02-08T12:43:55-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:55 volumio go-librespot[25667]: time="2026-02-08T12:43:55-06:00" level=debug msg="app state loaded" Feb 08 12:43:55 volumio go-librespot[25667]: time="2026-02-08T12:43:55-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:55 volumio go-librespot[25667]: time="2026-02-08T12:43:55-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:55 volumio volumio[25219]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:43:55 volumio sudo[25674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:43:55 volumio sudo[25674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:56 volumio sudo[25674]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:56 volumio volumio[25219]: info: Completed starting Core Plugins Feb 08 12:43:56 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:56 volumio volumio[25219]: info: ----- MyVolumio plugins startup ---- Feb 08 12:43:56 volumio volumio[25219]: info: ------------------------------------------- Feb 08 12:43:56 volumio volumio[25219]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=info msg="zeroconf server listening on port 35747" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="obtained new client token: AACtCX8L9uQAG6uVj3JVwBZgxckhPd/BLhW5iR7SEffYqw1CSIqS7nZsIsG0BBJXpoqPO/sqNsHsNng/0+4MtPCJqKHVhAYq1eKl8sn0tv5kmhyMHWVQO0mYCXH01wa3JXzF+2AT7WiCm01m9gWVDRzqHqf/2mvgqFxvvacY6kg1azduFaNsNnvw2xs6tjvuXJ/ss5v7YiDPGEmYK7bnEs9lwQsg+2DuMEEKMVTlURd63N2rlMt7FSU=" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="completed keyexchange" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=debug msg="completed challenge" Feb 08 12:43:56 volumio go-librespot[25667]: time="2026-02-08T12:43:56-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:43:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:43:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:43:56 volumio volumio[25219]: info: Initializing connection to go-librespot Websocket Feb 08 12:43:56 volumio volumio[25219]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:43:58 volumio sudo[25679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:43:58 volumio sudo[25679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:58 volumio sudo[25681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:43:58 volumio sudo[25681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:58 volumio sudo[25683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:43:58 volumio sudo[25683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:43:58 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 08 12:43:58 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 08 12:43:58 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 08 12:43:58 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 12:43:58 volumio sudo[25683]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:58 volumio sudo[25681]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:58 volumio sudo[25679]: pam_unix(sudo:session): session closed for user root Feb 08 12:43:58 volumio mpd_monitor.sh[25687]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 12:43:58 volumio volumio[25219]: info: Successfully started MPD Monitor Feb 08 12:43:58 volumio volumio[25219]: info: Successfully started MPD Monitor Feb 08 12:43:58 volumio volumio[25219]: info: Successfully started MPD Monitor Feb 08 12:43:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88. Feb 08 12:43:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:43:59 volumio go-librespot[25691]: go-librespot daemon starting... Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="app state loaded" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="stored credentials not found" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:43:59 volumio volumio[25219]: info: Initializing connection to go-librespot Websocket Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="new websocket client" Feb 08 12:43:59 volumio volumio[25219]: info: Connection to go-librespot Websocket established Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=info msg="zeroconf server listening on port 32951" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="obtained new client token: AAA+P5BykDHXT07UWIEunlMvNZBRcI05J155vYHVsII75L0BS2XTqfH+tYtwfRWQcQOoiEjmGS38QLez2BQC7duYVVliQjCFAY6mQC6RzSuvndVT7G7OTpyLrGyYHcIlzyAYmvjFnR3DU1eRSOngcmu3PcxpuI7/b1/RIuGvpFqFX8WUCmsl8pn3ZD7fS3OzuJoiK1QrS7pwz0nRX04QnogwChV4wdoIWoHCKO85nSGC/ZhQEesTgQ9fiw==" Feb 08 12:43:59 volumio go-librespot[25692]: time="2026-02-08T12:43:59-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:00 volumio go-librespot[25692]: time="2026-02-08T12:44:00-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:00 volumio go-librespot[25692]: time="2026-02-08T12:44:00-06:00" level=debug msg="completed challenge" Feb 08 12:44:00 volumio go-librespot[25692]: time="2026-02-08T12:44:00-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:00 volumio volumio[25219]: info: Connection to go-librespot Websocket closed Feb 08 12:44:02 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:44:02 volumio volumio[25219]: info: Getting Spotify volume Feb 08 12:44:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89. Feb 08 12:44:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:03 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:03 volumio go-librespot[25716]: go-librespot daemon starting... Feb 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-06:00" level=debug msg="app state loaded" Feb 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-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 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-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 08 12:44:03 volumio go-librespot[25717]: time="2026-02-08T12:44:03-06:00" level=info msg="zeroconf server listening on port 46249" Feb 08 12:44:04 volumio go-librespot[25717]: time="2026-02-08T12:44:04-06:00" level=debug msg="obtained new client token: AABju05n6XetA7yTIZ7wY0irsiVWqH611BUA/nGG8gyYt15Y9dz0vCOaanyYGi1SkEDbkvm/t0CHJ4YChMtBLgxVImfT60rkA6tUGaMUr/y3YOMpE1l8br/Xc/J9tmddzA4aVQLVOLQpYwCtulmvTXJWFEu7ScSgpLN6JMwQw4BdxeN9nPYt0A/SpV4XMzjoyo2a9EoqMIREJHe5v7bz6y8koBKywdh7DFmiKUjGVBH1pJlm5C3kJBg=" Feb 08 12:44:04 volumio go-librespot[25717]: time="2026-02-08T12:44:04-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:04 volumio go-librespot[25717]: time="2026-02-08T12:44:04-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:04 volumio go-librespot[25717]: time="2026-02-08T12:44:04-06:00" level=debug msg="completed challenge" Feb 08 12:44:04 volumio go-librespot[25717]: time="2026-02-08T12:44:04-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:04 volumio volumio[25219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 12:44:04 volumio volumio[25219]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:44:04 volumio volumio[25219]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 08 12:44:04 volumio volumio[25219]: errno: -111, Feb 08 12:44:04 volumio volumio[25219]: code: 'ECONNREFUSED', Feb 08 12:44:04 volumio volumio[25219]: syscall: 'connect', Feb 08 12:44:04 volumio volumio[25219]: address: '127.0.0.1', Feb 08 12:44:04 volumio volumio[25219]: port: 9879, Feb 08 12:44:04 volumio volumio[25219]: response: undefined Feb 08 12:44:04 volumio volumio[25219]: } Feb 08 12:44:04 volumio volumio[25219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 12:44:05 volumio sudo[25739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 12:43' Feb 08 12:44:05 volumio sudo[25739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:05 volumio sudo[25739]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:05 volumio volumio-remote-updater[751]: [2026-02-08 12:44:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 08 12:44:05 volumio volumio-remote-updater[751]: [2026-02-08 12:44:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 08 12:44:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 08 12:44:05 volumio systemd[1]: volumio.service: Consumed 51.001s CPU time. Feb 08 12:44:05 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 12:44:05 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 12:44:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1143. Feb 08 12:44:05 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 08 12:44:05 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 08 12:44:05 volumio systemd[1]: volumio.service: Consumed 51.001s CPU time. Feb 08 12:44:05 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 08 12:44:05 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 08 12:44:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90. Feb 08 12:44:07 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:07 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:07 volumio go-librespot[25768]: go-librespot daemon starting... Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="app state loaded" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-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 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-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 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=info msg="zeroconf server listening on port 42085" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="obtained new client token: AAAUtk9nCJ5bA8z3ujiocf21lx+lek8BmSpmf3Ax3BwRzFETYab7+v+fE/3JpF5bZaGXW5gk4wAW/pCJxr+yyOE+nxgPhgxU90WBAz4MnvytN4INiO5Z/bI2QowFp+GOiI7vcliIiSGavQrR0ghznotkwSPGAyYJpEPityI2Yyze2ETw3bsBuWuMBVYlOQVpXfVtHklXsPtV56jX58FmZQWlBufvmCrP2TTkdlE/QawT36R1I2+MXfYoPQ==" Feb 08 12:44:07 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:07 volumio volumio[25752]: info: ----- Volumio3 ---- Feb 08 12:44:07 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:07 volumio volumio[25752]: info: ----- System startup ---- Feb 08 12:44:07 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-06:00" level=debug msg="completed challenge" Feb 08 12:44:07 volumio go-librespot[25769]: time="2026-02-08T12:44:07-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 08 12:44:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:08 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:44:08 volumio volumio[25752]: info: MYVOLUMIO Environment detected Feb 08 12:44:08 volumio volumio[25752]: info: Plugin folders cleanup Feb 08 12:44:08 volumio volumio[25752]: info: Scanning into folder /volumio/app/plugins/ Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category audio_interface Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category miscellanea Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category music_service Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category plugins.json Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category system_controller Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category user_interface Feb 08 12:44:08 volumio volumio[25752]: info: Scanning into folder /data/plugins/ Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category audio_interface Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category music_service Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category system_controller Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category system_hardware Feb 08 12:44:08 volumio volumio[25752]: info: Scanning category user_interface Feb 08 12:44:08 volumio volumio[25752]: info: Plugin folders cleanup completed Feb 08 12:44:08 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:08 volumio volumio[25752]: info: ----- Core plugins startup ---- Feb 08 12:44:08 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:08 volumio volumio[25752]: info: Loading plugins from folder /volumio/app/plugins/ Feb 08 12:44:08 volumio volumio[25752]: info: Adding plugin upnp to MyMusic Plugins Feb 08 12:44:08 volumio volumio[25752]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 08 12:44:08 volumio volumio[25752]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 08 12:44:08 volumio volumio[25752]: info: Loading plugins from folder /data/plugins/ Feb 08 12:44:08 volumio volumio[25752]: info: Loading plugin "system"... Feb 08 12:44:08 volumio volumio[25752]: info: Loading plugin "appearance"... Feb 08 12:44:09 volumio volumio[25752]: info: Loading plugin "network"... Feb 08 12:44:09 volumio volumio[25752]: info: Refreshing Cached IP Addresses Feb 08 12:44:09 volumio sudo[25790]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 12:44:09 volumio sudo[25790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:09 volumio sudo[25792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 12:44:09 volumio sudo[25790]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:09 volumio volumio[25752]: info: Loading plugin "services"... Feb 08 12:44:09 volumio sudo[25792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:09 volumio volumio[25752]: info: Loading plugin "alsa_controller"... Feb 08 12:44:09 volumio sudo[25792]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:09 volumio sudo[25799]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 08 12:44:09 volumio sudo[25799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:09 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:44:09 volumio volumio[25752]: info: Loading plugin "wizard"... Feb 08 12:44:09 volumio volumio[25752]: info: Loading plugin "networkfs"... Feb 08 12:44:10 volumio volumio[25752]: info: Starting Udev Watcher for removable devices Feb 08 12:44:10 volumio volumio[25752]: info: Ignoring mount for partition: boot Feb 08 12:44:10 volumio volumio[25752]: info: Ignoring mount for partition: volumio Feb 08 12:44:10 volumio volumio[25752]: info: Ignoring mount for partition: volumio_data Feb 08 12:44:10 volumio volumio[25752]: info: Mounting Device Wikipedia Feb 08 12:44:10 volumio sudo[25829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Wikipedia -o noatime Feb 08 12:44:10 volumio sudo[25829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:10 volumio sudo[25829]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:10 volumio volumio[25752]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 12:44:10 volumio volumio[25752]: dmesg(1) may have more information after failed mount system call. Feb 08 12:44:10 volumio volumio[25752]: error: Failed to mount Wikipedia: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/Wikipedia" -o noatime Feb 08 12:44:10 volumio volumio[25752]: mount: /media/Wikipedia: /dev/sda1 already mounted on /media/Wikipedia. Feb 08 12:44:10 volumio volumio[25752]: dmesg(1) may have more information after failed mount system call. Feb 08 12:44:10 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 12:44:10 volumio volumio[25752]: info: Loading plugin "volumio_command_line_client"... Feb 08 12:44:10 volumio volumio[25752]: info: Loading plugin "upnp"... Feb 08 12:44:10 volumio volumio[25752]: info: [1770576250251] Starting Upmpd Daemon Feb 08 12:44:10 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 12:44:10 volumio volumio[25752]: info: Loading plugin "my_music"... Feb 08 12:44:10 volumio volumio[25752]: info: Loading plugin "mpd"... Feb 08 12:44:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:44:10 volumio sudo[25799]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:10 volumio volumio-remote-updater[751]: [2026-02-08 12:44:10] [connect] Successful connection Feb 08 12:44:10 volumio volumio[25752]: info: Loading plugin "upnp_browser"... Feb 08 12:44:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 91. Feb 08 12:44:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:11 volumio go-librespot[25831]: go-librespot daemon starting... Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="app state loaded" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=info msg="zeroconf server listening on port 41623" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="obtained new client token: AABPpoXPpOzSEaWfOFmRxoM21iX5LGpkBhpWNh9aB4kOjzry22mtV3j8PTF7ESXV9tM0uk4MHFcJMcQPjLBERXzkZ/WHcPBatIdmA0C/CgUvoofIOjGlfc6zpRbenIMqH80r52zTS55ySg9V5nBMbrFOI6E9a4u+es7RHlCUaDs0eM7fQdYYeo0yu5xQOlNmAWKAeynrLaHQBWoLlDQ5vnItQEtU512vHN/0IU0R8Ro7WsSpMGPdqkUdjA==" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-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 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp 104.154.127.247:443: connect: connection refused" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="connected to ap-guc3.spotify.com:80" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-06:00" level=debug msg="completed challenge" Feb 08 12:44:11 volumio go-librespot[25832]: time="2026-02-08T12:44:11-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 08 12:44:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "alarm-clock"... Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "airplay_emulation"... Feb 08 12:44:13 volumio volumio[25752]: info: Starting Shairport Sync Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "last_100"... Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "webradio"... Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "i2s_dacs"... Feb 08 12:44:13 volumio volumio[25752]: info: I2S DAC not set, start Auto-detection Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "volumiodiscovery"... Feb 08 12:44:13 volumio volumio[25752]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 12:44:13 volumio volumio[25752]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:44:13 volumio volumio[25752]: *** WARNING *** For more information see Feb 08 12:44:13 volumio volumio[25752]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 12:44:13 volumio volumio[25752]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:44:13 volumio volumio[25752]: *** WARNING *** For more information see Feb 08 12:44:13 volumio node[25752]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 08 12:44:13 volumio node[25752]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:44:13 volumio node[25752]: *** WARNING *** For more information see Feb 08 12:44:13 volumio node[25752]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 08 12:44:13 volumio node[25752]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 08 12:44:13 volumio node[25752]: *** WARNING *** For more information see Feb 08 12:44:13 volumio volumio[25752]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 08 12:44:13 volumio volumio[25752]: info: Discovery: Started advertising with name: Volumio Feb 08 12:44:13 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 08 12:44:13 volumio volumio[25752]: info: Loading plugin "bandcamp"... Feb 08 12:44:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 92. Feb 08 12:44:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:15 volumio go-librespot[25842]: go-librespot daemon starting... Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="app state loaded" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:15 volumio volumio[25752]: info: Plugin calmradio is not enabled Feb 08 12:44:15 volumio volumio[25752]: info: Loading plugin "soundcloud"... Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=info msg="zeroconf server listening on port 39923" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="obtained new client token: AADoV8aCQPZPMzIBR2Pf9kd/B7UBAwhiPSrTcIgkNovT2CMVqiooIHFBDzXt3qHhBB7BZwvSnTlQJY9SQTHjCTGAYly8d+Abt0yHFF1F18y2k09hyxIW2MjkdWJI/Caw0QBjs5TbuIfyX5xMCAxE8Psi/AhHY2lngdqcfkjTLaPUnr9bHFao2pzRXWBnY0RAlchCwCRHYSyGgimwSqNnynO3iBRwoAwrOIN4m38CEShmZPgG6E+NdrwCXg==" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=debug msg="completed challenge" Feb 08 12:44:15 volumio volumio[25752]: info: Loading plugin "spop"... Feb 08 12:44:15 volumio go-librespot[25843]: time="2026-02-08T12:44:15-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:16 volumio volumio[25752]: info: Loading plugin "squeezelite_mc"... Feb 08 12:44:16 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:44:17 volumio volumio[25752]: info: Loading plugin "youtube2"... Feb 08 12:44:18 volumio volumio[25752]: info: Loading plugin "ytcr"... Feb 08 12:44:18 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:44:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 93. Feb 08 12:44:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:18 volumio go-librespot[25854]: go-librespot daemon starting... Feb 08 12:44:18 volumio go-librespot[25855]: time="2026-02-08T12:44:18-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:18 volumio go-librespot[25855]: time="2026-02-08T12:44:18-06:00" level=debug msg="app state loaded" Feb 08 12:44:18 volumio go-librespot[25855]: time="2026-02-08T12:44:18-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:18 volumio go-librespot[25855]: time="2026-02-08T12:44:18-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-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 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-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 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-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 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-06:00" level=info msg="zeroconf server listening on port 36631" Feb 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-06:00" level=debug msg="obtained new client token: AABQC/8Zy37KJ1s5oUa5qlBVllpTvhWhHP/NjGUw0celpRt7JUJsNyYMIRuo0SvJLDq8ur9E5xpeKft9vSIn97q9R2oawcDt0LfBeV+JL4afamwzoNLu5f/vhJIFEInoizk/+rCR14fkZigtGPDcrK4giqBmVGgVbD0cuH1got5EDUCaspm2NsZQwBcvbV5uGbrPy1aNQlb/abPhsG5ZsgT1ATa8QgZVFtdinESqy0JgTOzaRe3eT47Zig==" Feb 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-06:00" level=debug msg="completed challenge" Feb 08 12:44:19 volumio go-librespot[25855]: time="2026-02-08T12:44:19-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:20 volumio volumio[25752]: info: Loading plugin "ytmusic"... Feb 08 12:44:21 volumio volumio[25752]: info: Plugin now_playing is not enabled Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "outputs"... Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "albumart"... Feb 08 12:44:21 volumio volumio[25752]: info: Plugin example_plugin is not enabled Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "inputs"... Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "updater_comm"... Feb 08 12:44:21 volumio volumio[25752]: info: Plugin mpdemulation is not enabled Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "rest_api"... Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "websocket"... Feb 08 12:44:21 volumio volumio[25752]: info: Starting Socket.io Server version 1.7.4 Feb 08 12:44:21 volumio volumio[25752]: info: Plugin fusiondsp is not enabled Feb 08 12:44:21 volumio volumio[25752]: info: Plugin mpdoutput is not enabled Feb 08 12:44:21 volumio volumio[25752]: info: Plugin RoonBridge is not enabled Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "podcast"... Feb 08 12:44:21 volumio volumio[25752]: info: ControllerPodcast::constructor Feb 08 12:44:21 volumio volumio[25752]: info: Loading plugin "volusonic"... Feb 08 12:44:21 volumio volumio[25877]: Forking 3 albumart workers Feb 08 12:44:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 94. Feb 08 12:44:22 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:22 volumio go-librespot[25918]: go-librespot daemon starting... Feb 08 12:44:22 volumio go-librespot[25919]: time="2026-02-08T12:44:22-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:22 volumio go-librespot[25919]: time="2026-02-08T12:44:22-06:00" level=debug msg="app state loaded" Feb 08 12:44:22 volumio go-librespot[25919]: time="2026-02-08T12:44:22-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:22 volumio go-librespot[25919]: time="2026-02-08T12:44:22-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:23 volumio volumio-remote-updater[751]: [2026-02-08 12:44:23] [connect] Successful connection Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-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 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-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 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-06:00" level=info msg="zeroconf server listening on port 35725" Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-06:00" level=debug msg="obtained new client token: AABMbf9nuyM83+0tMkkuiwV+ykKC7hS8MTIEf31ZejGKgo+kLFOjg3BclB1ohVZ07SaHRYgNDFCi8QE6g/ohjIQmzbIV+Ykqs3K/KcB2J+qPbyUj05cXmw023cxOUvGtGH0doifw3J2XKjEy2dBhRoZHMkL+HxdqPyUf/TWkpoKnoaN7yskpWb/Ldfn+FZSMnJyHvBElbuLGJBJQXmdNmrapz1epQYmYaHFKiaprWKzNtoRigeadvg4mSA==" Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-06:00" level=debug msg="completed challenge" Feb 08 12:44:23 volumio go-librespot[25919]: time="2026-02-08T12:44:23-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 08 12:44:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:24 volumio volumio[25889]: Starting albumart workers Feb 08 12:44:24 volumio volumio[25752]: info: Applying required configuration parameters for plugin volusonic Feb 08 12:44:24 volumio volumio[25752]: info: Loading plugin "backup_restore"... Feb 08 12:44:24 volumio volumio[25887]: Starting albumart workers Feb 08 12:44:24 volumio volumio[25752]: info: Applying required configuration parameters for plugin backup_restore Feb 08 12:44:24 volumio volumio[25752]: info: Plugin rpi_eeprom_config is not enabled Feb 08 12:44:24 volumio volumio[25752]: info: Plugin rpi_eeprom_updater is not enabled Feb 08 12:44:24 volumio volumio[25752]: info: Loading plugin "scheduledrestart"... Feb 08 12:44:24 volumio volumio[25888]: Starting albumart workers Feb 08 12:44:25 volumio volumio[25752]: info: Applying required configuration parameters for plugin scheduledrestart Feb 08 12:44:25 volumio volumio[25752]: info: Plugin Bluetoothremote is not enabled Feb 08 12:44:25 volumio volumio[25752]: info: Plugin music_services_shield is not enabled Feb 08 12:44:25 volumio volumio[25752]: info: Loading plugin "Systeminfo"... Feb 08 12:44:25 volumio volumio[25752]: info: Plugin peppymeterbasic is not enabled Feb 08 12:44:25 volumio volumio[25752]: info: Loading plugin "peppyspectrum"... Feb 08 12:44:26 volumio volumio[25752]: info: Loading i18n strings for locale en Feb 08 12:44:26 volumio volumio[25752]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 12:44:26 volumio volumio[25752]: Updating browse sources language Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::initPlayerControls Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: Express server listening on port 3000 Feb 08 12:44:26 volumio volumio[25752]: [Metrics] WebUI: 19s 77.42ms Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::resetVolumioState Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::getcurrentVolume Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:26 volumio sudo[25949]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 08 12:44:26 volumio sudo[25949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio sudo[25949]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:26 volumio sudo[25951]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 08 12:44:26 volumio sudo[25951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio sudo[25951]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:26 volumio volumio[25752]: info: Volumio Network Manager: Network status updated: 1 Feb 08 12:44:26 volumio volumio-remote-updater[751]: [2026-02-08 12:44:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770576263 101 Feb 08 12:44:26 volumio volumio[25752]: 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 08 12:44:26 volumio volumio[25752]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::pushState Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioPushState Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::updateTrackBlock Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrackBlock Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:44:26 volumio volumio[25752]: info: Reloading queue from file Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::setRepeat null single undefined Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::pushState Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioPushState Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::setRandom null Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::pushState Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioPushState Feb 08 12:44:26 volumio volumio[25752]: info: Setting Device type: Raspberry PI Feb 08 12:44:26 volumio volumio[25752]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:44:26 volumio volumio[25752]: info: CoreStateMachine::pushState Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioPushState Feb 08 12:44:26 volumio sudo[25964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 08 12:44:26 volumio sudo[25964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio volumio[25752]: info: Completed loading Core Plugins Feb 08 12:44:26 volumio volumio[25752]: info: Preparing to generate the ALSA configuration file Feb 08 12:44:26 volumio volumio[25752]: info: Discovery: adding a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 12:44:26 volumio volumio[25752]: info: Discovery: Found device Volumio Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioGetState Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:26 volumio sudo[25964]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:26 volumio volumio[25752]: info: Discovery: this is already registered, a135e40e-a404-457b-98f5-36bf71c75954 Feb 08 12:44:26 volumio volumio[25752]: info: Discovery: Found device Volumio Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioGetState Feb 08 12:44:26 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:26 volumio volumio[25752]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 12:44:26 volumio volumio[25752]: info: Reading ALSA contributions from plugins. Feb 08 12:44:26 volumio volumio[25752]: info: Upmpdcli Daemon Started Feb 08 12:44:26 volumio volumio[25752]: info: Asound.conf file unchanged, so no further update is needed Feb 08 12:44:26 volumio volumio[25752]: info: Output device has changed, restarting MPD Feb 08 12:44:26 volumio volumio[25752]: info: Output device has changed, restarting Shairport Sync Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:26 volumio sudo[25967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:44:26 volumio sudo[25967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio sudo[25967]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:26 volumio sudo[25969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:44:26 volumio sudo[25969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio volumio[25752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:44:26 volumio volumio[25752]: info: ___________ START PLUGINS ___________ Feb 08 12:44:26 volumio volumio[25752]: info: ControllerMpd::onStart: Initializing MPD Feb 08 12:44:26 volumio volumio[25752]: info: Creating MPD Configuration file Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:26 volumio volumio[25752]: info: [1770576266878] CoreMusicLibrary::Adding element Media Servers Feb 08 12:44:26 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:26 volumio sudo[25977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:44:26 volumio sudo[25977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio sudo[25979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:44:26 volumio sudo[25979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:26 volumio sudo[25977]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 95. Feb 08 12:44:26 volumio volumio[25752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:26 volumio volumio[25752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:44:26 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:26 volumio volumio[25752]: info: [1770576266999] CoreMusicLibrary::Adding element Last_100 Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267004] CoreMusicLibrary::Adding element Webradio Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:44:27 volumio volumio[25752]: info: Initializing BBC Radios Feb 08 12:44:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:27 volumio go-librespot[25988]: go-librespot daemon starting... Feb 08 12:44:27 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 12:44:27 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 12:44:27 volumio systemd[1]: mpd.service: Consumed 4.278s CPU time. Feb 08 12:44:27 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 12:44:27 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 12:44:27 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 12:44:27 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="app state loaded" Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267111] CoreMusicLibrary::Adding element Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267125] CoreMusicLibrary::Adding element SoundCloud Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: info: Creating Spotify config file Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio sudo[26011]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 12:44:27 volumio sudo[26011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-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 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-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 08 12:44:27 volumio sudo[26011]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=info msg="zeroconf server listening on port 43031" Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="obtained new client token: AAC37CiEwVyJEUg6N+bCs7+0W2jfC/aKbaIvReCjjbzSQasMXY/nd4jz24if9vqId4tXCIrvEJ+EbjB91Seo2zzxgESoEL+Ici+ugjON75oUccVBpBuR8ZSs2UyPODfbe/Xflz4wbrBKtgiQjITKJWqWVpcRYoGjBwcqSoR6J1/Iwhu6k6giPo6q1vzG2MzBZpW35o3Qf7s+Elfv1/yVnxl6knnwegx3GkbLmRSpvgFwZjrE3QQGJKDhxg==" Feb 08 12:44:27 volumio volumio[25752]: info: [squeezelite_mc] Starting proxy server... Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267528] CoreMusicLibrary::Adding element YouTube2 Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267546] CoreMusicLibrary::Adding element YouTube Music Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube Music Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267559] CoreMusicLibrary::Adding element Podcast Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube Music Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Podcast Feb 08 12:44:27 volumio volumio[25752]: info: Loading i18n strings for locale en Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=debug msg="completed challenge" Feb 08 12:44:27 volumio volumio[25752]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Feb 08 12:44:27 volumio volumio[25752]: Updating browse sources language Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube Music Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Podcast Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube Music Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Podcast Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:27 volumio volumio[25752]: info: [1770576267608] CoreMusicLibrary::Adding element Volusonic Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source YouTube Music Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Podcast Feb 08 12:44:27 volumio volumio[25752]: Cannot find translation for source Volusonic Feb 08 12:44:27 volumio go-librespot[26000]: time="2026-02-08T12:44:27-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:27 volumio volumio[25752]: info: Scheduling reboots with hours: 0, minutes: 0 in timezone: America/Chicago Feb 08 12:44:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 08 12:44:27 volumio volumio[25752]: info: Volumio Calling Home Feb 08 12:44:27 volumio volumio[25752]: info: [squeezelite_mc] Proxy server started on port 32965 Feb 08 12:44:27 volumio volumio[25752]: info: Preparing to generate the ALSA configuration file Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::volumioRetrievevolume Feb 08 12:44:27 volumio volumio[25752]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 08 12:44:27 volumio volumio[25752]: info: Reading ALSA contributions from plugins. Feb 08 12:44:27 volumio volumio[25752]: info: MPD Permissions set Feb 08 12:44:27 volumio volumio[25752]: info: MPD Permissions set Feb 08 12:44:27 volumio volumio[25752]: info: Spotify config file written Feb 08 12:44:27 volumio volumio[25752]: 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 08 12:44:27 volumio sudo[26023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio sudo[26023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:27 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: No need to fix Spotify hosts Feb 08 12:44:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:28 volumio go-librespot[26035]: go-librespot daemon starting... Feb 08 12:44:28 volumio sudo[26023]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="app state loaded" Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:28 volumio volumio[25752]: 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 08 12:44:28 volumio volumio[25752]: info: VolumeController:: Volume=100 Mute =false Feb 08 12:44:28 volumio volumio[25752]: info: CoreStateMachine::pushState Feb 08 12:44:28 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::volumioPushState Feb 08 12:44:28 volumio volumio[25752]: info: [squeezelite_mc] Server discovery started Feb 08 12:44:28 volumio volumio[25752]: info: [squeezelite_mc] Player finder started Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 08 12:44:28 volumio volumio[25752]: info: Volumio called home Feb 08 12:44:28 volumio volumio[25752]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 12:44:28 volumio volumio[25752]: SPOTIFY: BQDShxKfkFfU6kis8sxkSpCdmlO7yZfV8QCeU15je2xrOzOASfURLP7pTGl3gv2SNs4YUUor51ntBv52_00qbK82gIKMDgix83QThuPrNjUqaLtp9G6YF79VUGhp1cw7KgCJMIF5Rb2QomXJxyunUndc54xrPbnrXrDH_Ind4ssjm6dqg2qy4B5TgstU-TgjE9LmGsMTvTbtg0npx3g0FPHEdNJzVX23VCADmyw Feb 08 12:44:28 volumio volumio[25752]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 08 12:44:28 volumio volumio[25752]: info: New Spotify access token = BQDShxKfkFfU6kis8sxkSpCdmlO7yZfV8QCeU15je2xrOzOASfURLP7pTGl3gv2SNs4YUUor51ntBv52_00qbK82gIKMDgix83QThuPrNjUqaLtp9G6YF79VUGhp1cw7KgCJMIF5Rb2QomXJxyunUndc54xrPbnrXrDH_Ind4ssjm6dqg2qy4B5TgstU-TgjE9LmGsMTvTbtg0npx3g0FPHEdNJzVX23VCADmyw Feb 08 12:44:28 volumio volumio[25752]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 08 12:44:28 volumio volumio[25752]: info: [squeezelite_mc] Card 1 supports the following ALSA formats: ["U8","S16_LE"] Feb 08 12:44:28 volumio volumio[25752]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"1","mixerType":"Hardware","mixer":"PCM"} Feb 08 12:44:28 volumio volumio[25752]: 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 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:44:28 volumio volumio[25752]: info: Starting Shairport Sync Feb 08 12:44:28 volumio volumio[25752]: info: Starting Shairport Sync Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=info msg="zeroconf server listening on port 38529" Feb 08 12:44:28 volumio volumio[25752]: info: Starting Shairport Sync Feb 08 12:44:28 volumio sudo[26047]: 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 08 12:44:28 volumio sudo[26047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio sudo[26053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:44:28 volumio sudo[26053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio sudo[26047]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:28 volumio volumio[25752]: info: Asound.conf file unchanged, so no further update is needed Feb 08 12:44:28 volumio volumio[25752]: info: Output device has changed, restarting MPD Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="obtained new client token: AACU/3/3FlLmDvSfj9Hri0vSP4JkPGLJQ11lQTs+C2rkThpq3t3nj9EPYfWyc2GrdSnXpp3GF1COGDo48WGAVMcu8crm2rLYhAjEN4favk2OYNQZJAAxxUtT6GMX2E7eagKcvU0AO88wBhlhHNIKjEi9RkdgY4Gji2O/0T7oQdlnRnG8+byc4R4JQom0fkRGXjO+HAnfaSM/RFDq3qICPdKKHq0VnnbNNScqbg4yNlFuyrzkWeBUn8a1qw==" Feb 08 12:44:28 volumio volumio[25752]: info: Output device has changed, restarting Shairport Sync Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:28 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:44:28 volumio sudo[26051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:44:28 volumio sudo[26051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:44:28 volumio sudo[26059]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 08 12:44:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:44:28 volumio systemd[1]: shairport-sync.service: Consumed 2.100s CPU time. Feb 08 12:44:28 volumio sudo[26059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio sudo[26057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 08 12:44:28 volumio sudo[26057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio sudo[26049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:44:28 volumio sudo[26057]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:28 volumio sudo[26049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio volumio[25752]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:44:28 volumio volumio[25752]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-06:00" level=debug msg="completed challenge" Feb 08 12:44:28 volumio sudo[26053]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:28 volumio go-librespot[26036]: time="2026-02-08T12:44:28-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 08 12:44:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:28 volumio sudo[26074]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 08 12:44:28 volumio sudo[26074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:44:28 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 08 12:44:28 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 08 12:44:28 volumio systemd[1]: mpd.service: Consumed 1.585s CPU time. Feb 08 12:44:28 volumio sudo[26074]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:44:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:44:28 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 08 12:44:28 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 08 12:44:28 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 08 12:44:28 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 08 12:44:28 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 08 12:44:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:44:28 volumio volumio[25752]: info: MPD Permissions set Feb 08 12:44:28 volumio sudo[26049]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:29 volumio sudo[26051]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:29 volumio kernel: hwmon hwmon1: Voltage normalised Feb 08 12:44:29 volumio volumio[25752]: 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 08 12:44:29 volumio volumio[25752]: info: Shairport-Sync Started Feb 08 12:44:29 volumio volumio[25752]: Error adding Membership: Error: addMembership EINVAL Feb 08 12:44:29 volumio volumio[25752]: info: Shairport-Sync Started Feb 08 12:44:29 volumio volumio[25752]: info: Shairport-Sync Started Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio sudo[26108]: 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 08 12:44:29 volumio sudo[26108]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:29 volumio sudo[26108]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::volumioGetState Feb 08 12:44:29 volumio volumio[25752]: info: CorePlayQueue::getTrack 0 Feb 08 12:44:29 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 08 12:44:29 volumio sudo[26090]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 08 12:44:29 volumio sudo[26090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 08 12:44:29 volumio volumio[25752]: info: Starting Shairport Sync Feb 08 12:44:29 volumio sudo[26090]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:29 volumio sudo[26117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 08 12:44:29 volumio sudo[26117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:29 volumio sudo[26122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 08 12:44:29 volumio sudo[26122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:29 volumio volumio[25752]: 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 08 12:44:29 volumio volumio[25752]: info: Spotify Successfully logged in Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 08 12:44:29 volumio volumio[25752]: info: [1770576269333] CoreMusicLibrary::Adding element Spotify Feb 08 12:44:29 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source Bandcamp Discover Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source SoundCloud Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source YouTube2 Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source YouTube Music Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source Podcast Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source Volusonic Feb 08 12:44:29 volumio volumio[25752]: Cannot find translation for source Spotify Feb 08 12:44:29 volumio systemd[1]: Reloading. Feb 08 12:44:30 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:30 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:30 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 08 12:44:30 volumio volumio[25752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 08 12:44:31 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 08 12:44:31 volumio volumio[25752]: info: go-librespot daemon successfully initialized Feb 08 12:44:32 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 08 12:44:32 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 08 12:44:32 volumio sudo[26117]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 96. Feb 08 12:44:32 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:32 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:32 volumio sudo[26156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:32 volumio sudo[26156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:32 volumio go-librespot[26157]: go-librespot daemon starting... Feb 08 12:44:32 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:32 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 08 12:44:32 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 08 12:44:32 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:44:32 volumio systemd[1]: shairport-sync.service: Consumed 1.489s CPU time. Feb 08 12:44:32 volumio go-librespot[26159]: time="2026-02-08T12:44:32-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:32 volumio go-librespot[26159]: time="2026-02-08T12:44:32-06:00" level=debug msg="app state loaded" Feb 08 12:44:32 volumio go-librespot[26159]: time="2026-02-08T12:44:32-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:32 volumio go-librespot[26159]: time="2026-02-08T12:44:32-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:33 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 08 12:44:33 volumio sudo[26122]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:33 volumio volumio[25752]: info: Shairport-Sync Started Feb 08 12:44:33 volumio sudo[26156]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:33 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 08 12:44:33 volumio sudo[26167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 08 12:44:33 volumio sudo[26167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:33 volumio systemd[1]: Stopping squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=info msg="zeroconf server listening on port 46405" Feb 08 12:44:33 volumio systemd[1]: squeezelite.service: Deactivated successfully. Feb 08 12:44:33 volumio systemd[1]: Stopped squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 12:44:33 volumio sudo[26167]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="obtained new client token: AADuxCSuQO7EiDvVkS0abridCHjFZNGY8zM+8oE26y4bqk9g3omty/nYsWqE1ChtSmxuSAGTVNqSe/KXl/GFfaXRFXEcrm2MrKMhAqVPeJvP0r+yg3GpNSABzhqG+8Dvgscn87zyquHsuggUxxSXGAYolS8nND7vQuKO2AuVa4W/DwQAl6LtseJTBUtLC0VHK/rbZrfCy69UNs+Y9RHbPclzClW+Tn9p2gPrWc/6Tnx0Lelh4NndEMr75Q==" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-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 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="connected to ap-guc3.spotify.com:443" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44:33-06:00" level=debug msg="completed challenge" Feb 08 12:44:33 volumio go-librespot[26159]: time="2026-02-08T12:44: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 08 12:44:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:33 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:33 volumio sudo[26186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:33 volumio sudo[26186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:33 volumio sudo[26186]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:34 volumio volumio[25752]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 08 12:44:34 volumio sudo[26190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 08 12:44:34 volumio sudo[26190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:34 volumio sudo[26190]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:34 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 08 12:44:34 volumio sudo[26193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 08 12:44:34 volumio sudo[26193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:34 volumio mpd[26121]: 2026-02-08T12:44:34 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 08 12:44:34 volumio systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Feb 08 12:44:34 volumio systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Feb 08 12:44:34 volumio sudo[26193]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:34 volumio volumio[25752]: info: Initializing connection to go-librespot Websocket Feb 08 12:44:34 volumio volumio[25752]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 08 12:44:34 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 08 12:44:34 volumio sudo[25969]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:34 volumio sudo[26059]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:34 volumio sudo[25979]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:34 volumio volumio[25752]: error: MPD error: The expression evaluated to a falsy value: Feb 08 12:44:34 volumio volumio[25752]: assert.ok(self.idling) Feb 08 12:44:34 volumio volumio[25752]: error: The expression evaluated to a falsy value: Feb 08 12:44:34 volumio volumio[25752]: assert.ok(self.idling) Feb 08 12:44:34 volumio volumio[25752]: error: MPD error: The expression evaluated to a falsy value: Feb 08 12:44:34 volumio volumio[25752]: assert.ok(self.idling) Feb 08 12:44:34 volumio volumio[25752]: error: The expression evaluated to a falsy value: Feb 08 12:44:34 volumio volumio[25752]: assert.ok(self.idling) Feb 08 12:44:34 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:34 volumio volumio[25752]: info: MPD running with PID26121 Feb 08 12:44:34 volumio volumio[25752]: ,establishing connection Feb 08 12:44:34 volumio volumio[25752]: error: updateQueue error: null Feb 08 12:44:34 volumio volumio[25752]: error: updateQueue error: null Feb 08 12:44:34 volumio sudo[26204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:34 volumio sudo[26204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:34 volumio sudo[26204]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:35 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:35 volumio sudo[26207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:35 volumio sudo[26207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:35 volumio sudo[26207]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:36 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:36 volumio sudo[26210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:36 volumio sudo[26210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:36 volumio sudo[26210]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 97. Feb 08 12:44:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:36 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:36 volumio sudo[26214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:36 volumio sudo[26214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:36 volumio go-librespot[26212]: go-librespot daemon starting... Feb 08 12:44:36 volumio go-librespot[26216]: time="2026-02-08T12:44:36-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:36 volumio go-librespot[26216]: time="2026-02-08T12:44:36-06:00" level=debug msg="app state loaded" Feb 08 12:44:36 volumio go-librespot[26216]: time="2026-02-08T12:44:36-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:36 volumio go-librespot[26216]: time="2026-02-08T12:44:36-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:36 volumio sudo[26214]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-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 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-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 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-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 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=info msg="zeroconf server listening on port 43357" Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=debug msg="obtained new client token: AAC/9y/p2OtEYvMgj5KVUAama9Ptlw1nXs+e4dYyDc4a0OHtc4plkom+QdXxrclDEola0V0f606cpYGN7Q0ao8k5OhxF4k/TRVMbWP7FaRJ9uTDDWlbBeLUMKn03p5zJQaG/fIKnZnvYWKVFKm7wVaPWgtdX6uUXCsxxTuAOaskkg8dIt5PgOG2+hhxnFE5r9MXIe6xFhzK0ZrKSaVCnO/5aqjkWZdNP8KLXN/0hqmugl1u/DznhbVesXw==" Feb 08 12:44:37 volumio volumio[25752]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 08 12:44:37 volumio volumio[25752]: info: Initializing connection to go-librespot Websocket Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=debug msg="new websocket client" Feb 08 12:44:37 volumio volumio[25752]: info: Connection to go-librespot Websocket established Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:37 volumio sudo[26225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 08 12:44:37 volumio sudo[26225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=debug msg="completed challenge" Feb 08 12:44:37 volumio sudo[26228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:44:37 volumio sudo[26228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:37 volumio go-librespot[26216]: time="2026-02-08T12:44:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:37 volumio sudo[26230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:44:37 volumio sudo[26230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:37 volumio volumio[25752]: info: Connection to go-librespot Websocket closed Feb 08 12:44:37 volumio sudo[26225]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:37 volumio sudo[26233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 08 12:44:37 volumio sudo[26233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 12:44:37 volumio volumio[25752]: info: Completed starting Core Plugins Feb 08 12:44:37 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:37 volumio volumio[25752]: info: ----- MyVolumio plugins startup ---- Feb 08 12:44:37 volumio volumio[25752]: info: ------------------------------------------- Feb 08 12:44:37 volumio volumio[25752]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 08 12:44:37 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 08 12:44:37 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 08 12:44:37 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 08 12:44:37 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 08 12:44:37 volumio mpd_monitor.sh[26236]: MPD Monitor Service: Starting MPD Monitor Service Feb 08 12:44:37 volumio sudo[26230]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:37 volumio sudo[26228]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:37 volumio sudo[26233]: pam_unix(sudo:session): session closed for user root Feb 08 12:44:37 volumio volumio[25752]: info: Successfully started MPD Monitor Feb 08 12:44:37 volumio volumio[25752]: info: Successfully started MPD Monitor Feb 08 12:44:37 volumio volumio[25752]: info: Successfully started MPD Monitor Feb 08 12:44:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 98. Feb 08 12:44:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 08 12:44:40 volumio go-librespot[26255]: go-librespot daemon starting... Feb 08 12:44:40 volumio go-librespot[26256]: time="2026-02-08T12:44:40-06:00" level=info msg="running go-librespot 0.4.0" Feb 08 12:44:40 volumio go-librespot[26256]: time="2026-02-08T12:44:40-06:00" level=debug msg="app state loaded" Feb 08 12:44:40 volumio go-librespot[26256]: time="2026-02-08T12:44:40-06:00" level=debug msg="stored credentials not found" Feb 08 12:44:40 volumio go-librespot[26256]: time="2026-02-08T12:44:40-06:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 08 12:44:41 volumio volumio[25752]: info: Getting Spotify volume Feb 08 12:44:41 volumio volumio[25752]: info: Initializing connection to go-librespot Websocket Feb 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-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 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-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 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-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 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-06:00" level=info msg="zeroconf server listening on port 34323" Feb 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-06:00" level=debug msg="obtained new client token: AADKTP0vb7hXV8lDZLXua8sXc2rgEmcEtBKrWLsQuYUfZU2rKx5pVs+t8n8OpVdJrc/cnKA/9O4nv/VSaLgH5kWziK97Lu2WDu1z4dpOuC39WetwXgLzom2dnJBA7gBKAvYX4PjOr8RRnajb0xVt6hkCKDrXS5Nsd0KCGHo1OSlUa/XBnO2xG7na3PANuNM1JbCMYwN3qCvmSUhy8NauuELxFY/lHYJHgR2dHWj15zt+KVN50ES+r0cpEQ==" Feb 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-06:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-06:00" level=debug msg="completed keyexchange" Feb 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-06:00" level=debug msg="completed challenge" Feb 08 12:44:41 volumio go-librespot[26256]: time="2026-02-08T12:44:41-06:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Feb 08 12:44:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 08 12:44:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 08 12:44:42 volumio volumio[25752]: info: Error connecting to go-librespot Websocket: Error: socket hang up Feb 08 12:44:42 volumio volumio[25752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 12:44:42 volumio volumio[25752]: Error: socket hang up Feb 08 12:44:42 volumio volumio[25752]: at connResetException (node:internal/errors:720:14) Feb 08 12:44:42 volumio volumio[25752]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 08 12:44:42 volumio volumio[25752]: at Socket.emit (node:events:526:35) Feb 08 12:44:42 volumio volumio[25752]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 08 12:44:42 volumio volumio[25752]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 08 12:44:42 volumio volumio[25752]: code: 'ECONNRESET', Feb 08 12:44:42 volumio volumio[25752]: response: undefined Feb 08 12:44:42 volumio volumio[25752]: } Feb 08 12:44:42 volumio volumio[25752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 12:44:43 volumio sudo[26278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 12:43' Feb 08 12:44:43 volumio sudo[26278]: 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"