Aug 26 17:23:05 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:06 office-speakers systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 313369. Aug 26 17:23:06 office-speakers systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:06 office-speakers systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:06 office-speakers upmpdcli[8758]: Could not open config: /tmp/upmpdcli.conf Aug 26 17:23:06 office-speakers systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:23:06 office-speakers systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 17:23:12 office-speakers volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:23:15 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:15 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:15 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:22 office-speakers systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 313370. Aug 26 17:23:22 office-speakers systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:22 office-speakers systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:22 office-speakers upmpdcli[8787]: Could not open config: /tmp/upmpdcli.conf Aug 26 17:23:22 office-speakers systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:23:22 office-speakers systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 17:23:25 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:35 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:35 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:35 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:37 office-speakers systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 313371. Aug 26 17:23:37 office-speakers systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:37 office-speakers sudo[8805]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 17:23:37 office-speakers systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:37 office-speakers sudo[8805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:37 office-speakers sudo[8805]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:37 office-speakers sudo[8807]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 17:23:37 office-speakers sudo[8807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:37 office-speakers sudo[8807]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:37 office-speakers upmpdcli[8803]: Could not open config: /tmp/upmpdcli.conf Aug 26 17:23:37 office-speakers systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:23:37 office-speakers volumio[1236]: verbose: New Socket.io Connection to 192.168.8.199 from 192.168.8.186 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 Aug 26 17:23:37 office-speakers systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 17:23:37 office-speakers sudo[8826]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 17:23:37 office-speakers sudo[8827]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 17:23:37 office-speakers sudo[8826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:37 office-speakers sudo[8827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:37 office-speakers sudo[8826]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:37 office-speakers sudo[8827]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:37 office-speakers volumio[1236]: verbose: New Socket.io Connection to 192.168.8.199 from 192.168.8.186 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 9 Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 17:23:37 office-speakers volumio[1236]: info: Received Get System Info Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 17:23:37 office-speakers volumio[1236]: info: Discovery: Getting this device information Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:37 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 17:23:37 office-speakers volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:23:38 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 17:23:39 office-speakers volumio[1236]: info: Received Get System Info Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 17:23:39 office-speakers volumio[1236]: info: Discovery: Getting this device information Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 17:23:39 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 26 17:23:40 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 17:23:40 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 26 17:23:40 office-speakers volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:23:42 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 17:23:42 office-speakers volumio[1236]: info: Received Get System Info Aug 26 17:23:42 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 17:23:42 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 17:23:42 office-speakers volumio[1236]: info: Discovery: Getting this device information Aug 26 17:23:42 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:42 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 17:23:45 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:47 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 17:23:47 office-speakers volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:23:51 office-speakers volumio[1236]: info: Received OAUTH Data Aug 26 17:23:51 office-speakers volumio[1236]: info: Executing Spotify Oauth Login Aug 26 17:23:51 office-speakers volumio[1236]: info: Saving Spotify Refresh Token Aug 26 17:23:51 office-speakers volumio[1236]: info: New Spotify access tokenBQBcH3k79c... Aug 26 17:23:51 office-speakers volumio[1236]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 26 17:23:51 office-speakers volumio[1236]: SPOTIFY: User informations: {"account_id":"8rVydeOoFX","country":"GB","display_name":"eavenell","email":"eavenell@yahoo.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/eavenell"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/eavenell","id":"eavenell","images":[],"product":"premium","type":"user","uri":"spotify:user:eavenell"} Aug 26 17:23:51 office-speakers volumio[1236]: info: Creating Spotify config file Aug 26 17:23:51 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:23:51 office-speakers volumio[1236]: info: Spotify config file written Aug 26 17:23:51 office-speakers sudo[8848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 26 17:23:51 office-speakers sudo[8848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:51 office-speakers systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 26 17:23:51 office-speakers systemd[1]: go-librespot-daemon.service: Killing process 8631 (go-librespot) with signal SIGKILL. Aug 26 17:23:51 office-speakers systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 26 17:23:51 office-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:23:51 office-speakers volumio[1236]: info: Connection to go-librespot Websocket closed Aug 26 17:23:51 office-speakers volumio[1236]: info: Connection to go-librespot Websocket closed Aug 26 17:23:51 office-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:23:51 office-speakers sudo[8848]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:51 office-speakers go-librespot[8850]: go-librespot daemon starting... Aug 26 17:23:51 office-speakers go-librespot[8851]: time="2026-08-26T17:23:51+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:23:51 office-speakers go-librespot[8851]: time="2026-08-26T17:23:51+01:00" level=debug msg="app state loaded" Aug 26 17:23:51 office-speakers go-librespot[8851]: time="2026-08-26T17:23:51+01:00" level=debug msg="stored credentials not found" Aug 26 17:23:51 office-speakers volumio[1236]: info: New Spotify access tokenBQD-GWemb2... Aug 26 17:23:51 office-speakers volumio[1236]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 26 17:23:51 office-speakers go-librespot[8851]: time="2026-08-26T17:23:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:23:52 office-speakers volumio[1236]: SPOTIFY: User informations: {"account_id":"8rVydeOoFX","country":"GB","display_name":"eavenell","email":"eavenell@yahoo.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/eavenell"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/eavenell","id":"eavenell","images":[],"product":"premium","type":"user","uri":"spotify:user:eavenell"} Aug 26 17:23:52 office-speakers volumio[1236]: info: Spotify Successfully logged in Aug 26 17:23:52 office-speakers volumio[1236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 17:23:52 office-speakers volumio[1236]: info: [1787761432009] CoreMusicLibrary::Adding element Spotify Aug 26 17:23:52 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 17:23:52 office-speakers volumio[1236]: Cannot find translation for source Spotify Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=info msg="zeroconf server listening on port 39143" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="obtained new client token: AAFGHdVz3Y/3yRpltC6s2P8BNLYgFcDWKZZg8aR27qIzUWk3rkfWA5Tc2bQLw+GuNsFtqY3DKb/JQoiKoLObvKGUroAzU61WrEUkI6efWQ+fOwHY0dMlqxrGEvhJX1gitBip2HApB3iTB3tW8vp40Xac588PxL9Ftl5XQPgpj+TvIRHLTI+xU86bTtpnhVWixwlrznYz6xwWz7lguNaS8cyYS+HPjIZxiqp5sorqERiCiaczt14B" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="completed keyexchange" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=debug msg="completed challenge" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=info msg="authenticated AP" username="ea****ll" Aug 26 17:23:52 office-speakers go-librespot[8851]: time="2026-08-26T17:23:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:23:52 office-speakers systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 313372. Aug 26 17:23:52 office-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:23:52 office-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:23:52 office-speakers systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:52 office-speakers systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:23:52 office-speakers upmpdcli[8860]: Could not open config: /tmp/upmpdcli.conf Aug 26 17:23:52 office-speakers systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:23:52 office-speakers systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 17:23:53 office-speakers sudo[8862]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 17:23:53 office-speakers sudo[8862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:53 office-speakers sudo[8862]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:53 office-speakers sudo[8864]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 17:23:53 office-speakers sudo[8864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:23:53 office-speakers sudo[8864]: pam_unix(sudo:session): session closed for user root Aug 26 17:23:53 office-speakers volumio[1236]: verbose: New Socket.io Connection to 192.168.8.199 from 192.168.8.186 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.6.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 9 Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 17:23:53 office-speakers volumio[1236]: info: Received Get System Info Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 17:23:53 office-speakers volumio[1236]: info: Discovery: Getting this device information Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:53 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 17:23:53 office-speakers volumio[1236]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:23:54 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 17:23:54 office-speakers volumio[1236]: info: Initializing connection to go-librespot Websocket Aug 26 17:23:54 office-speakers volumio[1236]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:23:54 office-speakers volumio[1236]: info: go-librespot daemon successfully initialized Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:55 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:55 office-speakers volumio[1236]: info: Listing playlists Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 17:23:55 office-speakers volumio[1236]: info: Received Get System Info Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 17:23:55 office-speakers volumio[1236]: info: Discovery: Getting this device information Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:55 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 17:23:55 office-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 26 17:23:55 office-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:23:55 office-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:23:55 office-speakers go-librespot[8868]: go-librespot daemon starting... Aug 26 17:23:55 office-speakers go-librespot[8869]: time="2026-08-26T17:23:55+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:23:55 office-speakers go-librespot[8869]: time="2026-08-26T17:23:55+01:00" level=debug msg="app state loaded" Aug 26 17:23:55 office-speakers go-librespot[8869]: time="2026-08-26T17:23:55+01:00" level=debug msg="stored credentials not found" Aug 26 17:23:55 office-speakers go-librespot[8869]: time="2026-08-26T17:23:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:23:56 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 17:23:56 office-speakers volumio[1236]: info: Received Get System Info Aug 26 17:23:56 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 17:23:56 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 17:23:56 office-speakers volumio[1236]: info: Discovery: Getting this device information Aug 26 17:23:56 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:23:56 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=info msg="zeroconf server listening on port 32925" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="obtained new client token: AAHs7e3zDPqPrjB+FhFSCk8axPqt5Ca6HuORuqOZ+moe2eAeM+DEvWAYAW0Cla6UQagVIniA5zDUDC75r3woElJO2qleeSlaSDaMUODETlmLeuJ4aqABp5oQ/vO/LzsEV+fok2WuuVFh1NJaigon8r04NP5oaDKPXNByNfxXl5gIxMzEfxuNHtrgC47zbv2XXwP20bfLVMifiEc8seR14F3y9ePYxn3MMN65wmyjZQqRL+PQ4+zX" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="completed keyexchange" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=debug msg="completed challenge" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=info msg="authenticated AP" username="ea****ll" Aug 26 17:23:56 office-speakers go-librespot[8869]: time="2026-08-26T17:23:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:23:56 office-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:23:56 office-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:23:57 office-speakers volumio[1236]: info: Initializing connection to go-librespot Websocket Aug 26 17:23:57 office-speakers volumio[1236]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:23:57 office-speakers volumio[1236]: info: Initializing connection to go-librespot Websocket Aug 26 17:23:57 office-speakers volumio[1236]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:23:59 office-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 26 17:23:59 office-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:23:59 office-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:23:59 office-speakers go-librespot[8892]: go-librespot daemon starting... Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=debug msg="app state loaded" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=debug msg="stored credentials not found" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=info msg="zeroconf server listening on port 36635" Aug 26 17:23:59 office-speakers go-librespot[8893]: time="2026-08-26T17:23:59+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:24:00 office-speakers go-librespot[8893]: time="2026-08-26T17:24:00+01:00" level=debug msg="obtained new client token: AAEaHUkQYLNUlilgvqhchxZp8zlcp6BhgoByvJZ+Ok3bUecAhImjGYjJD2OAutVYhCk+5TbeVGbEWwy+jkwEz4ZTtkINZPVWJybN+TUKkwyjXCiG/YL/AjYQpC6dVsmy8adUxnexkPDDQHGZrVQCISJSluWqvfPmhJz5qTLPmRvbJoXtjx8SaaviW/9zWeuuXOJ16Bou3xOH/Dsrm1clRRpsd88pI5sLPxrEqkrpqcY7FKlEEQM+" Aug 26 17:24:00 office-speakers go-librespot[8893]: time="2026-08-26T17:24:00+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:24:00 office-speakers go-librespot[8893]: time="2026-08-26T17:24:00+01:00" level=debug msg="completed keyexchange" Aug 26 17:24:00 office-speakers go-librespot[8893]: time="2026-08-26T17:24:00+01:00" level=debug msg="completed challenge" Aug 26 17:24:00 office-speakers go-librespot[8893]: time="2026-08-26T17:24:00+01:00" level=info msg="authenticated AP" username="ea****ll" Aug 26 17:24:00 office-speakers go-librespot[8893]: time="2026-08-26T17:24:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:24:00 office-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:24:00 office-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:24:00 office-speakers volumio[1236]: info: Initializing connection to go-librespot Websocket Aug 26 17:24:00 office-speakers volumio[1236]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:24:03 office-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 26 17:24:03 office-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:24:03 office-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:24:03 office-speakers go-librespot[8902]: go-librespot daemon starting... Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="app state loaded" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="stored credentials not found" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:24:03 office-speakers volumio[1236]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=info msg="zeroconf server listening on port 33387" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="obtained new client token: AAEE38neA6d9hFbGry+4gTackrmKyMgrJZM57SQuWfABMoko6Qj6KOxLXkYYjmvKJNwVHUNkjMb/tVcXH0h/uhKzuYctH5TSIE87rELrnsdU4Hx+f2+xbBIfpqyW1nDfRIit/wO+XNbWuhH6qyWp90VMQK12ng5mSSJvEZ64bAelI3fKNeCa6lw8wtV/6GGAjJ3TXGe1oyQOekpLjBSJFDHiyqnXQOmrNS+D3W8RlC/ghksugR8ADIo=" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:24:03 office-speakers volumio[1236]: info: Initializing connection to go-librespot Websocket Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="new websocket client" Aug 26 17:24:03 office-speakers volumio[1236]: info: Connection to go-librespot Websocket established Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="completed keyexchange" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=debug msg="completed challenge" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=info msg="authenticated AP" username="ea****ll" Aug 26 17:24:03 office-speakers go-librespot[8903]: time="2026-08-26T17:24:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:24:03 office-speakers volumio[1236]: info: Connection to go-librespot Websocket closed Aug 26 17:24:03 office-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:24:03 office-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:24:05 office-speakers volumio[1236]: info: CoreCommandRouter::volumioGetState Aug 26 17:24:06 office-speakers volumio[1236]: info: Getting Spotify volume Aug 26 17:24:06 office-speakers volumio[1236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:24:06 office-speakers volumio[1236]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 26 17:24:06 office-speakers volumio[1236]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 26 17:24:06 office-speakers volumio[1236]: errno: -111, Aug 26 17:24:06 office-speakers volumio[1236]: code: 'ECONNREFUSED', Aug 26 17:24:06 office-speakers volumio[1236]: syscall: 'connect', Aug 26 17:24:06 office-speakers volumio[1236]: address: '127.0.0.1', Aug 26 17:24:06 office-speakers volumio[1236]: port: 9879, Aug 26 17:24:06 office-speakers volumio[1236]: response: undefined Aug 26 17:24:06 office-speakers volumio[1236]: } Aug 26 17:24:06 office-speakers volumio[1236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:24:07 office-speakers systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 26 17:24:07 office-speakers systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:24:07 office-speakers systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 26 17:24:07 office-speakers go-librespot[8925]: go-librespot daemon starting... Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=info msg="running go-librespot 0.7.1" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="app state loaded" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="stored credentials not found" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=info msg="zeroconf server listening on port 46769" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="obtained new client token: AAHpu8/PZmVKufybf71m3od9ra01f18PgpFYZOyaAzfFLStDAj0pU+wRCULWFpTrKGukJWhEZZoFOJti+RV/cNICR5rnHe/uEjsbPcv0dMGKTHaFskdnw4Rbb+u+NkqYqeirBi3J23aVhJNy0noFZKHJ2Yyw1jB3cl3O27I1VdnAPM1JzvrI8OdCSKCmbc7a1bR6J+GtbuxGA9EXtjHbxP22OWhcRwtB005wfxnjmYNsrKWNQ3EaS4U=" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="completed keyexchange" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=debug msg="completed challenge" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=info msg="authenticated AP" username="ea****ll" Aug 26 17:24:07 office-speakers go-librespot[8926]: time="2026-08-26T17:24:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 26 17:24:07 office-speakers systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:24:07 office-speakers systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 26 17:24:07 office-speakers systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 313373. Aug 26 17:24:07 office-speakers systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:24:07 office-speakers systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 26 17:24:07 office-speakers sudo[8953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 17:23' Aug 26 17:24:07 office-speakers sudo[8953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:24:07 office-speakers upmpdcli[8950]: Could not open config: /tmp/upmpdcli.conf Aug 26 17:24:07 office-speakers systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:24:07 office-speakers systemd[1]: upmpdcli.service: Failed with result 'exit-code'. 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"