Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 22:09:00 volumio volumio[1414]: info: Getting Alsa Cards List without I2S DAC Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 22:09:00 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 22:09:08 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 22:09:08 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 22:09:14 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 255. Oct 13 22:09:14 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:09:14 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:09:14 volumio upmpdcli[8634]: Could not open config: /tmp/upmpdcli.conf Oct 13 22:09:14 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:14 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 22:09:22 volumio volumio[1414]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 22:09:27 volumio volumio[1414]: info: Received OAUTH Data Oct 13 22:09:27 volumio volumio[1414]: info: Executing Spotify Oauth Login Oct 13 22:09:27 volumio volumio[1414]: info: Saving Spotify Refresh Token Oct 13 22:09:27 volumio volumio[1414]: info: New Spotify access tokenBQCwT2lHjK... Oct 13 22:09:27 volumio volumio[1414]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 13 22:09:28 volumio volumio[1414]: SPOTIFY: User informations: {"account_id":"2f3K5UVaf1","country":"GB","display_name":"Daniel Faulkner","email":"drfaulkner80@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/11162370200"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/11162370200","id":"11162370200","images":[{"height":300,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/468331358_10161495020740280_5095754349133120715_n.jpg?stp=c34.34.420.421a_dst-jpg_s320x320_tt6&_nc_cat=102&ccb=1-7&_nc_sid=08baa4&_nc_ohc=_Rw3xvStIZ8Q7kNvwH9tEng&_nc_oc=AdrrZgHZoIMVwpDU0xQrJGFmtWEFPD3-WHkGLPE_aBKYaHo2ur-Ae6oop0lUDrBsOckcRaZgU56VBlslc0dsMH08&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=kHcKgbG_Q3UeRqLV-wuH4g&_nc_tpa=Q5bMBQLvuZERcrfSBaDG9rHI6mqObFgwXRCQKbT9LsD1iP8lI-7OCJVHDIYJWDHic1oQsOmAs0RG&oh=00_AQOMsirGgUNkb-E5LNentir9YQQ3ID1F6SAZXdgsMd1ZdA&oe=6AD44D4A","width":300},{"height":64,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/468331358_10161495020740280_5095754349133120715_n.jpg?stp=c34.34.420.421a_cp0_dst-jpg_s50x50_tt6&_nc_cat=102&ccb=1-7&_nc_sid=28885b&_nc_ohc=_Rw3xvStIZ8Q7kNvwH9tEng&_nc_oc=AdrrZgHZoIMVwpDU0xQrJGFmtWEFPD3-WHkGLPE_aBKYaHo2ur-Ae6oop0lUDrBsOckcRaZgU56VBlslc0dsMH08&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=kHcKgbG_Q3UeRqLV-wuH4g&_nc_tpa=Q5bMBQKsc9MwAfSz-16gH7n1x40E4laW94FGa3F_bl_i44Udw9hfWp50IHbWwjk5zNoJTGdG6E2z&oh=00_AQNOy41r80Wo9tUd8xl16FIabHXvh6OJ7dAjrxO6ga8XYA&oe=6AD44D4A","width":64}],"product":"premium","type":"user","uri":"spotify:user:11162370200"} Oct 13 22:09:28 volumio volumio[1414]: info: Creating Spotify config file Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 22:09:28 volumio volumio[1414]: info: Spotify config file written Oct 13 22:09:28 volumio sudo[8667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 13 22:09:28 volumio sudo[8667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 22:09:28 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 13 22:09:28 volumio volumio[1414]: info: Connection to go-librespot Websocket closed Oct 13 22:09:28 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 13 22:09:28 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:28 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:28 volumio sudo[8667]: pam_unix(sudo:session): session closed for user root Oct 13 22:09:28 volumio go-librespot[8669]: go-librespot daemon starting... Oct 13 22:09:28 volumio sudo[8674]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 22:09:28 volumio sudo[8674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 22:09:28 volumio sudo[8672]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 22:09:28 volumio sudo[8674]: pam_unix(sudo:session): session closed for user root Oct 13 22:09:28 volumio sudo[8672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 22:09:28 volumio sudo[8672]: pam_unix(sudo:session): session closed for user root Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=debug msg="app state loaded" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:28 volumio volumio[1414]: info: New Spotify access tokenBQDa3R-ip5... Oct 13 22:09:28 volumio volumio[1414]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 13 22:09:28 volumio volumio[1414]: verbose: New Socket.io Connection to 192.168.1.211 from 192.168.1.26 UA: Mozilla/5.0 (Linux; Android 17; Pixel 10a Build/CP3A.260905.009; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 11 Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+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]" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+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]" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+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]" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=info msg="zeroconf server listening on port 40843" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=debug msg="obtained new client token: AAEEWrPyOlZPlyeNWi4xKeQAbByv4LT4BYoRM2cB4DEGZxfNnKJ4hARaONeJnGbPrVYaAwkTRCr1Y5B1vVvyrm0maVRg6nV+zYT2JKosLPpUAqjOlACQLodIouDAWYX8gHV8/N1h3csLgcbrG3KYqh5+rE+pwzxQNVczoDKWk+57gpj8rhB2Eggr5ePNF0U+Q+OtafPIhiU+LNpT3drXnPqgHTe28jhS9THw2nwJvKqUW1xwnxW1oas=" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 22:09:28 volumio volumio[1414]: SPOTIFY: User informations: {"account_id":"2f3K5UVaf1","country":"GB","display_name":"Daniel Faulkner","email":"drfaulkner80@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/11162370200"},"followers":{"href":null,"total":3},"href":"https://api.spotify.com/v1/users/11162370200","id":"11162370200","images":[{"height":300,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/468331358_10161495020740280_5095754349133120715_n.jpg?stp=c34.34.420.421a_dst-jpg_s320x320_tt6&_nc_cat=102&ccb=1-7&_nc_sid=08baa4&_nc_ohc=_Rw3xvStIZ8Q7kNvwH9tEng&_nc_oc=AdrrZgHZoIMVwpDU0xQrJGFmtWEFPD3-WHkGLPE_aBKYaHo2ur-Ae6oop0lUDrBsOckcRaZgU56VBlslc0dsMH08&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=kHcKgbG_Q3UeRqLV-wuH4g&_nc_tpa=Q5bMBQLvuZERcrfSBaDG9rHI6mqObFgwXRCQKbT9LsD1iP8lI-7OCJVHDIYJWDHic1oQsOmAs0RG&oh=00_AQOMsirGgUNkb-E5LNentir9YQQ3ID1F6SAZXdgsMd1ZdA&oe=6AD44D4A","width":300},{"height":64,"url":"https://scontent-ams2-1.xx.fbcdn.net/v/t39.30808-1/468331358_10161495020740280_5095754349133120715_n.jpg?stp=c34.34.420.421a_cp0_dst-jpg_s50x50_tt6&_nc_cat=102&ccb=1-7&_nc_sid=28885b&_nc_ohc=_Rw3xvStIZ8Q7kNvwH9tEng&_nc_oc=AdrrZgHZoIMVwpDU0xQrJGFmtWEFPD3-WHkGLPE_aBKYaHo2ur-Ae6oop0lUDrBsOckcRaZgU56VBlslc0dsMH08&_nc_zt=24&_nc_ht=scontent-ams2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=kHcKgbG_Q3UeRqLV-wuH4g&_nc_tpa=Q5bMBQKsc9MwAfSz-16gH7n1x40E4laW94FGa3F_bl_i44Udw9hfWp50IHbWwjk5zNoJTGdG6E2z&oh=00_AQNOy41r80Wo9tUd8xl16FIabHXvh6OJ7dAjrxO6ga8XYA&oe=6AD44D4A","width":64}],"product":"premium","type":"user","uri":"spotify:user:11162370200"} Oct 13 22:09:28 volumio volumio[1414]: info: Spotify Successfully logged in Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 22:09:28 volumio volumio[1414]: info: [1791925768329] CoreMusicLibrary::Adding element Spotify Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 22:09:28 volumio volumio[1414]: Cannot find translation for source Podcast Oct 13 22:09:28 volumio volumio[1414]: Cannot find translation for source USB AUDIO CODEC Oct 13 22:09:28 volumio volumio[1414]: Cannot find translation for source Spotify Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=debug msg="completed challenge" Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 22:09:28 volumio volumio[1414]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 22:09:28 volumio volumio[1414]: info: Received Get System Info Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 22:09:28 volumio volumio[1414]: info: Discovery: Getting this device information Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:28 volumio volumio[1414]: info: Listing playlists Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 22:09:28 volumio go-librespot[8675]: time="2026-10-13T22:09:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:28 volumio volumio[1414]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 22:09:29 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 22:09:30 volumio volumio[1414]: info: Received Get System Info Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 22:09:30 volumio volumio[1414]: info: Discovery: Getting this device information Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 22:09:30 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 256. Oct 13 22:09:30 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:09:30 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:09:30 volumio upmpdcli[8686]: Could not open config: /tmp/upmpdcli.conf Oct 13 22:09:30 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:30 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 22:09:30 volumio volumio[1414]: info: Received Get System Info Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 22:09:30 volumio volumio[1414]: info: Discovery: Getting this device information Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:30 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 22:09:31 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:31 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:31 volumio volumio[1414]: info: go-librespot daemon successfully initialized Oct 13 22:09:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 13 22:09:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:31 volumio go-librespot[8687]: go-librespot daemon starting... Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=debug msg="app state loaded" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+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]" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+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]" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+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]" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=info msg="zeroconf server listening on port 43383" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=debug msg="obtained new client token: AAFSEu4pbUBPgZOdbb6s/iabnroAmtZOps4FqPAYdmKFo9XMTwm+XUIDCHJX97mcPOpfFghU8mIERNGczRbZ17o/FtR89AEu2p5tKIj8XG/Vv27FbHN3lbgZfYJjY1YMa+cEQ2ueY56r0mynFR32uEuzzeswkUqfK22i+YkWEGmsRPfHpZC/WVOA3WTFu+i033s6T4ReWrkXkq1PKQ1inlqG1NzDvBwgVD/C19F7dJE01wFGmHbSmjI=" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=debug msg="completed challenge" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:31 volumio go-librespot[8688]: time="2026-10-13T22:09:31+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:34 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:34 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:34 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:34 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 13 22:09:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:35 volumio go-librespot[8697]: go-librespot daemon starting... Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=debug msg="app state loaded" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+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]" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+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]" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+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]" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=info msg="zeroconf server listening on port 40147" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=debug msg="obtained new client token: AAG2sa/jPPAKwzSj31TezYzzI0BqOBmKMsmukDzegX/oqYt4aZzzAgKc5U/gFnTNI1eaS78hgn2V81dLoNHfvQkyGY9wFgliGjles3atv/4J6v0GdsYaVBjCC3x7RX8gnZFe2w+tlR+uMbAJOeRkIaKozzp/1H/hVGVz0njyNt7CXsxX5LkT+RwKNt2aYfpANR2I+zrYlDIqdAgx8PoQQeewXhAssHc9eruNZp4ey8KQtEdErStLm2Q=" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=debug msg="completed challenge" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:35 volumio go-librespot[8698]: time="2026-10-13T22:09:35+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:37 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:37 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:38 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 22:09:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Oct 13 22:09:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:38 volumio go-librespot[8722]: go-librespot daemon starting... Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=debug msg="app state loaded" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+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]" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+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]" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+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]" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=info msg="zeroconf server listening on port 33565" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=debug msg="obtained new client token: AAH3l41gsJsC0KdHMjsKTxNBeszaC9o4RmT38I6e25JmSQu89KxM0GM3tK7lkFrH87Sh2wUTci5myE0RRm4yLU2LXq/gUTvbhS+7zCyU1gC/lplUWEmihVcgYKHy0Ja9lSatfIJbiqv1KxnNNs6Us5B9eBxbbxNmLDsHKhFvKijCULBVEaMgcVFU4R+KkeDN6lqQkFI1L4hvAV2H1rghUxb/WI7tFl73U/mJwiDU0dAVNm9D2z4SqEc=" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=debug msg="completed challenge" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:38 volumio go-librespot[8723]: time="2026-10-13T22:09:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:40 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:40 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:40 volumio volumio[1414]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 13 22:09:40 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 13 22:09:40 volumio volumio[1414]: info: Creating Spotify config file Oct 13 22:09:40 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 22:09:40 volumio volumio[1414]: info: Spotify config file written Oct 13 22:09:40 volumio sudo[8733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 13 22:09:40 volumio sudo[8733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 22:09:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:40 volumio go-librespot[8735]: go-librespot daemon starting... Oct 13 22:09:40 volumio sudo[8733]: pam_unix(sudo:session): session closed for user root Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=debug msg="app state loaded" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+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]" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+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]" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+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]" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=info msg="zeroconf server listening on port 45885" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=debug msg="obtained new client token: AAFjkUTJ62Re5FaxK3vnEQVMi7SyaCx8J0OFxSpFW2eqpLEf2C2l+HVTp0dgFXs00AWw+Iz2Z3P5mdHZmfi0o0z6zrTfXGtpkSxUFPydHVkFgm85Kz83oaBH3r6UbVrtuSbqQPn5JAW7uyu30f44EUthX9P1XAlyL9m7WKj20qDGOnRfEl6B6GegWhaOsYkhSPmSD3mUVncOk6COfMje/F5VKdGKAVauLtiIiZGLJbOLYeia5XlOom8=" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=debug msg="completed challenge" Oct 13 22:09:40 volumio go-librespot[8736]: time="2026-10-13T22:09:40+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:41 volumio go-librespot[8736]: time="2026-10-13T22:09:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:41 volumio volumio[1414]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 13 22:09:41 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 13 22:09:41 volumio volumio[1414]: info: Creating Spotify config file Oct 13 22:09:41 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 22:09:41 volumio volumio[1414]: info: Spotify config file written Oct 13 22:09:41 volumio sudo[8746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 13 22:09:41 volumio sudo[8746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 22:09:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:41 volumio go-librespot[8748]: go-librespot daemon starting... Oct 13 22:09:41 volumio sudo[8746]: pam_unix(sudo:session): session closed for user root Oct 13 22:09:41 volumio go-librespot[8749]: time="2026-10-13T22:09:41+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:41 volumio go-librespot[8749]: time="2026-10-13T22:09:41+01:00" level=debug msg="app state loaded" Oct 13 22:09:41 volumio go-librespot[8749]: time="2026-10-13T22:09:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+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]" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+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]" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+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]" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=info msg="zeroconf server listening on port 33653" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=debug msg="obtained new client token: AAH3/z1Ugoos7MC2HuBbRopEokz8H88oINKZ6RS8EuE2gkVDCeOFTOo7VPjh0h9kbeblo8/Zyz1Oif464be5wF4qvdy/D1jYE5+F85CJKEzaDC5oV1qPa1nQ4NY9rI7bTHcQF9zPqJ25VKr7qmiIYaUmffDXyBjUbcv50+RS6lZYTd/B/vXJpxGaC/ysg7JW9j5AMDzcHrWvlLQ553vUfhQQBIjzw5XNnylNHwMYwH/6Q+cWveJd" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=debug msg="completed challenge" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:42 volumio go-librespot[8749]: time="2026-10-13T22:09:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:43 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:43 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:43 volumio volumio[1414]: info: go-librespot daemon successfully initialized Oct 13 22:09:44 volumio volumio[1414]: info: go-librespot daemon successfully initialized Oct 13 22:09:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 257. Oct 13 22:09:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 13 22:09:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:45 volumio go-librespot[8760]: go-librespot daemon starting... Oct 13 22:09:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:09:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=debug msg="app state loaded" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:45 volumio upmpdcli[8762]: Could not open config: /tmp/upmpdcli.conf Oct 13 22:09:45 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:45 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+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]" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+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]" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+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]" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=info msg="zeroconf server listening on port 34185" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=debug msg="obtained new client token: AAFWFOrTP2LaLFEMmO6Sw758gox2jB6hI16WD5TpX1P+NPREn1+h/ZoT5Uo4LSp/DRtmG2lJUESzAjC8RZNVIMCwm1esRuKEf/x35SZLS7oi/bbq1TPPBgx/4wVRBoIw/3KAxk7aOaklM8vcEm9m5LnCl4oiZI4diFAn2pWBy7ctAQp1AFOYrlZRc9F0xD+Fv/RIgnfEnVxgY1SNkbfgatvw9ZALRUeVdVTCcXGcXLS2FJfu15e47wo=" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=debug msg="completed challenge" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:45 volumio go-librespot[8761]: time="2026-10-13T22:09:45+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:46 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:46 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 22:09:46 volumio volumio[1414]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 22:09:46 volumio volumio[1414]: info: Received Get System Version Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 22:09:46 volumio volumio[1414]: info: Received Get System Info Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 22:09:46 volumio volumio[1414]: info: Discovery: Getting this device information Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 22:09:46 volumio volumio[1414]: info: CoreCommandRouter::volumioGetQueue Oct 13 22:09:46 volumio volumio[1414]: info: CoreStateMachine::getQueue Oct 13 22:09:46 volumio volumio[1414]: info: CorePlayQueue::getQueue Oct 13 22:09:46 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:46 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:47 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:47 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:48 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 13 22:09:48 volumio volumio[1414]: info: In handleBrowseUri, curUri=spotify Oct 13 22:09:48 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:48 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:48 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:48 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 13 22:09:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:48 volumio go-librespot[8787]: go-librespot daemon starting... Oct 13 22:09:48 volumio go-librespot[8788]: time="2026-10-13T22:09:48+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:48 volumio go-librespot[8788]: time="2026-10-13T22:09:48+01:00" level=debug msg="app state loaded" Oct 13 22:09:48 volumio go-librespot[8788]: time="2026-10-13T22:09:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+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]" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+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]" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+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]" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=info msg="zeroconf server listening on port 33135" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=debug msg="obtained new client token: AAE1nTHdgNkxp6brnegiTgJh1CrY77AIBhaQC1Ng0Q/MCjY4PKYy540eMsAWnxjK74pDUL7+iQQ4j7358ROGclpOWWtLlWHEK6QFOcLGn09SkREvEAbySX+KNsGxcv541ewRjSP6Xk30T7yukv3+ODP20FS5E6oNX5SKN4fRGSyJRj1A8lKZZCluqCyAEx3yvelLTdUAbIQUoFkGAotSf6q+Fms8Hig8v6Aq+lQbnjg4Ded3Y06b" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=debug msg="completed challenge" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:49 volumio go-librespot[8788]: time="2026-10-13T22:09:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:50 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:50 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 13 22:09:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:52 volumio go-librespot[8797]: go-librespot daemon starting... Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=debug msg="app state loaded" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+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]" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+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]" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=info msg="zeroconf server listening on port 36897" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=debug msg="obtained new client token: AAFOKqYjvtv2MHriUSDNz3EP8eEHi3xTUyMIWHBPPw+ZYKcdffeXZ0yXUz/E4qvlDwP2A9Fc7gKak543wEYELYTpvO8itFx0sAFpw2+D2UUJkPaeA7dKZL7nEpIG4WerEffQmaAfHWqLR12HAgkzOxeMpwI8Ca+TX9gEFGVfri+9ym6g16ovFAF3e/c29qN0aRO84lObNDYpId432gbtR9zHdv0K3fOSQ7vG838e0ZTl288nLOYF41I=" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=debug msg="completed challenge" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:52 volumio go-librespot[8798]: time="2026-10-13T22:09:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:53 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:53 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:54 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 13 22:09:54 volumio volumio[1414]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks Oct 13 22:09:54 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:09:54 volumio volumio[1414]: info: Preloading song: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:09:54 volumio volumio[1414]: info: Exploding uri spotify:track:1060gzllf4b0UETAXisR5l in service spop Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:09:54 volumio volumio[1414]: info: Exploding uri spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m in service spop Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:09:54 volumio volumio[1414]: info: Exploding uri spotify:track:6fi18o5Jp6dAIcXPlgzhWF in service spop Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1060gzllf4b0UETAXisR5l","service":"spop","name":"Lights Out","artist":"Royal Blood","album":"How Did We Get So Dark?","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b27394b63000190c83e4dc4ea064","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:54 volumio volumio[1414]: info: Exploding uri spotify:track:5fpj84RsT9cOTfWUCxBTXu in service spop Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m","service":"spop","name":"Ten Over Ten","artist":"Royal Blood","album":"Ten Over Ten","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273fd43e91bf7fe781670cc98f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:54 volumio volumio[1414]: info: Exploding uri spotify:track:6voIJ7OWwRabSZDC77D5Hp in service spop Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6fi18o5Jp6dAIcXPlgzhWF","service":"spop","name":"Swallow The Bomb","artist":"Royal Blood","album":"Swallow The Bomb","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b2737132be28d6fd75455660d2e0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:54 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5fpj84RsT9cOTfWUCxBTXu","service":"spop","name":"Little Monster","artist":"Royal Blood","album":"Royal Blood","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c23906cd30a0564bcebb5e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:1INksmStBpxdNlBzwGUvsg in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6voIJ7OWwRabSZDC77D5Hp","service":"spop","name":"Trouble’s Coming","artist":"Royal Blood","album":"Typhoons","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273722fae9a2431e499d89416a3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:3MjrueDQKVr6xDDseZwhEd in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:102zQx1snTXKKFSYRN2bsT in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1INksmStBpxdNlBzwGUvsg","service":"spop","name":"Out of the Black","artist":"Royal Blood","album":"Royal Blood","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c23906cd30a0564bcebb5e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:0uObHujoqoTLLiA6FTslbq in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:102zQx1snTXKKFSYRN2bsT","service":"spop","name":"Bravado","artist":"Ben Key","album":"Bravado","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b273457a68e84ea09c7aec6b52da","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3MjrueDQKVr6xDDseZwhEd","service":"spop","name":"Figure It Out","artist":"Royal Blood","album":"Royal Blood","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c23906cd30a0564bcebb5e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:44zR1haEFzUOkNx2oNcDPx in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0uObHujoqoTLLiA6FTslbq","service":"spop","name":"Nice Questions And Bad Advice","artist":"Mister Sunshine","album":"Nice Questions And Bad Advice","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b2739fe150c2325108b997e5aec4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:0m9pyxMQlPXjH21HVGerdf in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:2s2SoKsM8E7WKtC2PJGLTl in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0m9pyxMQlPXjH21HVGerdf","service":"spop","name":"All My Demons Greeting Me As A Friend","artist":"AURORA","album":"All My Demons Greeting Me As A Friend (The Moth Tapes)","type":"song","duration":221,"albumart":"https://i.scdn.co/image/ab67616d0000b27325e554311c7b4eb4b72dddae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:0Wu0ngacZJaLFQ1J54C0m1 in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2s2SoKsM8E7WKtC2PJGLTl","service":"spop","name":"Lever","artist":"Gary Len","album":"robot","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b2734a74d9510ae37420753d385d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:0oRU4EvJPm62mu2pHej5Gd in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Wu0ngacZJaLFQ1J54C0m1","service":"spop","name":"The Worry","artist":"Petey USA","album":"oooo","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b2736403e07d0dc8a3d21e0f6857","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:6yVYFlUSFQolLOCKD4oFe3 in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0oRU4EvJPm62mu2pHej5Gd","service":"spop","name":"Do We Know Who You Are","artist":"Apachelux","album":"Do We Know Who You Are","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273a92168b668576b91dc5eae36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:3ra7fDLqfUHBWBL702fOAn in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:44zR1haEFzUOkNx2oNcDPx","service":"spop","name":"Turtle and the Hare","artist":"Monotronic","album":"Waiting for You","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273a7be8bfa1d87d53e95e51513","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6yVYFlUSFQolLOCKD4oFe3","service":"spop","name":"In This Place","artist":"Andy Korg","album":"In This Place","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b273094a76c726ae0d498c3b5ac7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:32Q9Vj540kLQrjQJxTVhL6 in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ra7fDLqfUHBWBL702fOAn","service":"spop","name":"Pep Talk","artist":"Death Cab for Cutie","album":"I Built You A Tower","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2731356b24d48cbabc44384e753","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:1dYERGH5j9DqDcqgVoj9Rd in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:32Q9Vj540kLQrjQJxTVhL6","service":"spop","name":"Tallahassee","artist":"Jamie T","album":"Ghosts (100 Days of Morning)","type":"song","duration":265,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf3960b540fd28fe3ff1bd84","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:2CmCUBIOVFNswRHaV8N77c in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:2GikowG1krEbxgwuXI3fSg in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2CmCUBIOVFNswRHaV8N77c","service":"spop","name":"Look Outside","artist":"Babes In Canyon","album":"Look Outside","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b273074b9320b429a9985e6f20b2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1dYERGH5j9DqDcqgVoj9Rd","service":"spop","name":"Wednesdays","artist":"Soft Loft","album":"Throw The Dice","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b2733249fd6e7c4fbf75b9837ef0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:0yC5UuQoxpw8j05SH8jhnX in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2GikowG1krEbxgwuXI3fSg","service":"spop","name":"Forever Lover (永远的爱人)","artist":"Chinese American Bear","album":"Dim Sum & Then Some","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273719e12c5c2560bc9e6dfb3f8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:5vIpkzvNbXlknNlXAF2rcc in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0yC5UuQoxpw8j05SH8jhnX","service":"spop","name":"Great Expectations","artist":"Casper Caan","album":"Last Chance","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b273a35fc4fc104985a01ebc9045","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:3dZTrUqLpivFUkSuIpSoyb in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:5DkFBDxHIFa7cj3oyqKxFa in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:09:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 13 22:09:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3dZTrUqLpivFUkSuIpSoyb","service":"spop","name":"Seriously","artist":"Editors","album":"Seriously","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b273639ac4061e9013fc714df837","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:55 volumio go-librespot[8807]: go-librespot daemon starting... Oct 13 22:09:55 volumio volumio[1414]: info: Exploding uri spotify:track:4HQyX6OyDXNus0f7aPrHpw in service spop Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:09:55 volumio go-librespot[8808]: time="2026-10-13T22:09:55+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:55 volumio go-librespot[8808]: time="2026-10-13T22:09:55+01:00" level=debug msg="app state loaded" Oct 13 22:09:55 volumio go-librespot[8808]: time="2026-10-13T22:09:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:55 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DkFBDxHIFa7cj3oyqKxFa","service":"spop","name":"The Tanks Are Coming Out","artist":"Nrvs","album":"The Tanks Are Coming Out","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b27361ce0af6f087494a32229934","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:4g3x8k65lqP9bRUvqD1oL0 in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vIpkzvNbXlknNlXAF2rcc","service":"spop","name":"Shooting the Moon - Handbell version","artist":"OK Go","album":"On Bells","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5e409ce8939808599f8bce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09: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]" Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09: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]" Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09: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]" Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0LRJx6EKkr2e5GmZYRe9Bj in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=info msg="zeroconf server listening on port 36171" Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4HQyX6OyDXNus0f7aPrHpw","service":"spop","name":"Three Things","artist":"The Big Moon","album":"Three Things","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273a57c877297592997214de52d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=debug msg="obtained new client token: AAEk2L4mAufIWqtIlPb+MILonuAPjb505F/TSJNknCJZL6+XzTBUGMlVPqG/+rg6nas09TSKxrqS3o2owqNW/HMROn0r6p6JTMo1QXgFBo1Tg2LAV2eslUgXg6j8uJ+A6/s9zT5e8ZE3Y4PgeNhs9ddbrtahr/I3EZ3RdvcSuvFt+R3bIEQCMUKGaiNjRLMlIvKx2IlPiUW28XwJo97sKT9eTmCDcnd8YDOBSbgelUW1m3YgxRXW" Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:4UbC0NniHL5o5Wt6CMttb6 in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=debug msg="completed challenge" Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0LRJx6EKkr2e5GmZYRe9Bj","service":"spop","name":"u r my fire (Eirra)","artist":"SOCKiTTOME","album":"u r my fire (Eirra)","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b2735a5cbdb6a82344bb17a0cdc1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:7iiNvEGq7WQarP2cngclRE in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0rFEAWKMih1r6l9ZydkvX2 in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4UbC0NniHL5o5Wt6CMttb6","service":"spop","name":"Wide Open Road","artist":"The Temper Trap","album":"Wide Open Road","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273d9e04741a767f2a66c8e5a15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4g3x8k65lqP9bRUvqD1oL0","service":"spop","name":"At The Time In New York","artist":"James Tonic","album":"Safety 2","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273fb30e353c5bb40eaa8561943","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio go-librespot[8808]: time="2026-10-13T22:09:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7iiNvEGq7WQarP2cngclRE","service":"spop","name":"F U WANT IT","artist":"Soft Loft","album":"Throw The Dice","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2733249fd6e7c4fbf75b9837ef0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:3TLpGYpiakD3iJYCveeyGP in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0rFEAWKMih1r6l9ZydkvX2","service":"spop","name":"Two Hearts","artist":"JJerome87","album":"The Canyon","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b2735ffa4c1196af0ee2f3e15ce1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:4iej0znQnZAym0PgU7PICD in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3TLpGYpiakD3iJYCveeyGP","service":"spop","name":"how to have a good day","artist":"Basement Alchemy","album":"introverse","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b273b14e964fd34c876bd9dcb8e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0ZhN4Ut2wgb0X4603DuCEP in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y","service":"spop","name":"Work It Out","artist":"Modern Cult","album":"Work It Out","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273d708ba8080d89d3696ab2c36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ZhN4Ut2wgb0X4603DuCEP","service":"spop","name":"sidekick","artist":"dirty brunette","album":"sidekick","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b2739f792e115336e719c18ee191","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:1AdaAAPN5ggOTBh9hW4qie in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo","service":"spop","name":"Stone Over Water","artist":"Death Cab for Cutie","album":"I Built You A Tower","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2731356b24d48cbabc44384e753","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0PpwkRqCbwyY3sCSBuZM0y in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4iej0znQnZAym0PgU7PICD","service":"spop","name":"Dying In Reverse","artist":"Cage The Elephant","album":"Dying In Reverse","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b2736b0bbf9f0f91be527104ca6e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1AdaAAPN5ggOTBh9hW4qie","service":"spop","name":"Brad's Cabana","artist":"Jean Noir","album":"Brad's Cabana","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b2731ec85eccbc957bcc8566efe0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:68QotiFmMAThnuyhwgcwIB in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:6wqRmSvHbSdD66iSaDA4ap in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:68QotiFmMAThnuyhwgcwIB","service":"spop","name":"Return - Handbell version","artist":"OK Go","album":"On Bells","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5e409ce8939808599f8bce5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:5mdHGc62AEqK11GAUozg3t in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0PpwkRqCbwyY3sCSBuZM0y","service":"spop","name":"Celestial Bodies","artist":"Angus & Julia Stone","album":"Karaoke Bar","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6c25a41d5bbfdb4d4ae0ac0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6wqRmSvHbSdD66iSaDA4ap","service":"spop","name":"It's All Just Memories","artist":"Louis Sévigny","album":"It's All Just Memories","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b2738aa24fa00588ec344b288b67","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:4CfMafMHXNqAEKcmh39mES in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5mdHGc62AEqK11GAUozg3t","service":"spop","name":"Ask For Water","artist":"Matt Berninger","album":"Type It Up","type":"song","duration":314,"albumart":"https://i.scdn.co/image/ab67616d0000b2736c49ab162f48850588c2e6b1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:52zWuwIaQb2wcPVsM143wP in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4CfMafMHXNqAEKcmh39mES","service":"spop","name":"Walkaway Music","artist":"JJerome87","album":"The Canyon","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b2735ffa4c1196af0ee2f3e15ce1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0DBoPzmkOR9XdAUy3RgvtH in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:52zWuwIaQb2wcPVsM143wP","service":"spop","name":"About You Now","artist":"Manic Youth","album":"About You Now","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273cbf5b4f5b6bbe5478e256deb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:2hpQOXEBsZGsN1o0K6zna1 in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:09:56 volumio volumio[1414]: info: Exploding uri spotify:track:0lFYsvuAsbUaW73DERGo8x in service spop Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2hpQOXEBsZGsN1o0K6zna1","service":"spop","name":"i love you every now","artist":"Basement Alchemy","album":"introverse","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273b14e964fd34c876bd9dcb8e8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:56 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:56 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:56 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0DBoPzmkOR9XdAUy3RgvtH","service":"spop","name":"Feels Right","artist":"Zimmer90","album":"Feels Right","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273b6dc20a73a8b9735c58338ca","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:57 volumio volumio[1414]: info: Exploding uri spotify:track:3MeFWVniBG4McV0nwNonrl in service spop Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0lFYsvuAsbUaW73DERGo8x","service":"spop","name":"Days Like This","artist":"Thandi","album":"Days Like This","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273f6b326a141d92253d9cf4570","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:57 volumio volumio[1414]: info: Exploding uri spotify:track:0RZTK66wRdfMMQFw8afAEU in service spop Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3MeFWVniBG4McV0nwNonrl","service":"spop","name":"Other Desert Cities","artist":"Babes In Canyon","album":"Other Desert Cities","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b273247ca2845ebc342459e144cd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:57 volumio volumio[1414]: info: Exploding uri spotify:track:5pvXidnXBgzfllwfWR3UNE in service spop Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:09:57 volumio volumio[1414]: info: Exploding uri spotify:track:2O1ElYUg86yy9diVhGddqD in service spop Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0RZTK66wRdfMMQFw8afAEU","service":"spop","name":"Hang Me Up To Dry - Phantogram Version","artist":"Cold War Kids","album":"Hang Me Up To Dry (Phantogram Version)","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b273cce0e497f0e70adf4acfe1f6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5pvXidnXBgzfllwfWR3UNE","service":"spop","name":"Hearts Are Opening Up - Passion Pit Remix","artist":"Casper Caan","album":"Last Chance","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b273a35fc4fc104985a01ebc9045","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:57 volumio volumio[1414]: info: Exploding uri spotify:track:64q8nvkJGFyFR00cbtEjr3 in service spop Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: EXPLODING URI:spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2O1ElYUg86yy9diVhGddqD","service":"spop","name":"Just Drive","artist":"Natalie Imbruglia","album":"Algorithm","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b27395113392aa69dda2d32a238d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:57 volumio volumio[1414]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:64q8nvkJGFyFR00cbtEjr3","service":"spop","name":"Riptides","artist":"Death Cab for Cutie","album":"I Built You A Tower","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2731356b24d48cbabc44384e753","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 13 22:09:58 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::ClearQueue Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::stop Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::clearPlayQueue Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::addQueueItems Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::addQueueItems Oct 13 22:09:58 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::updateTrackBlock Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::getTrackBlock Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::getTrack 0 Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::volumioPlay Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::play index 3 Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::addQueueItems Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::addQueueItems Oct 13 22:09:58 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:09:58 volumio volumio[1414]: info: Adding Item to queue: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:09:58 volumio volumio[1414]: info: Using cached record of: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::stop Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:09:58 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::play index undefined Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::updateTrackBlock Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::getTrackBlock Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:09:58 volumio volumio[1414]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:09:58 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:09:58 volumio volumio[1414]: info: [1791925798975] ControllerSpotify::clearAddPlayTrack Oct 13 22:09:58 volumio volumio[1414]: info: Sending Spotify command with payload to local API: /player/play Oct 13 22:09:58 volumio volumio[1414]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:09:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 13 22:09:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:09:59 volumio go-librespot[8831]: go-librespot daemon starting... Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=debug msg="app state loaded" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09: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-gew4.spotify.com:80]" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+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]" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+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]" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=info msg="zeroconf server listening on port 41105" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=debug msg="obtained new client token: AAEAQBt4QSk29Oq3Rgp0ZlOZ8vs96rlPgBzBCZaQUxLkTPYjH/0+pwEQKzRP50mXEIp/gmQK4PY/JrEvQRC/B0JyW92yuNAhoKcqKDA/7qIa5Hx4NxBzfFYNtUs6CfhniKNGeJSCtedQRAtflpMIkPZsaxDE3ZKe0O8+p6YzVz+U0LZxKX9yFuJ0+2KnJT//m3KcQxLe07HI/RLl//WQphjxyayf7RN3zja/vJhlgfhGH6V3782I4N0=" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=debug msg="completed keyexchange" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=debug msg="completed challenge" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:09:59 volumio go-librespot[8832]: time="2026-10-13T22:09:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:09:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:09:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:09:59 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:09:59 volumio volumio[1414]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:10:00 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 258. Oct 13 22:10:00 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:10:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 22:10:00 volumio upmpdcli[8841]: Could not open config: /tmp/upmpdcli.conf Oct 13 22:10:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:10:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 22:10:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 13 22:10:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:10:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 13 22:10:02 volumio go-librespot[8842]: go-librespot daemon starting... Oct 13 22:10:02 volumio go-librespot[8843]: time="2026-10-13T22:10:02+01:00" level=info msg="running go-librespot 0.6.2" Oct 13 22:10:02 volumio go-librespot[8843]: time="2026-10-13T22:10:02+01:00" level=debug msg="app state loaded" Oct 13 22:10:02 volumio go-librespot[8843]: time="2026-10-13T22:10:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 13 22:10:02 volumio volumio[1414]: info: Initializing connection to go-librespot Websocket Oct 13 22:10:02 volumio go-librespot[8843]: time="2026-10-13T22:10:02+01:00" level=debug msg="new websocket client" Oct 13 22:10:02 volumio volumio[1414]: info: Connection to go-librespot Websocket established Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+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]" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+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]" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=info msg="zeroconf server listening on port 34175" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=debug msg="obtained new client token: AAH6xviOoGoPshqz1ZHdaR/2D2Zl+Ze/djfdRgK38NJhwtCkYH9oaPDT4l5cIIyp2q4jqaMieG6DwoAs4/3SCm6lG9dYe7vioLlBOHAzqFOHnbijdFAbVLZuLpRYJLuqYSNLILuDSYXVhSXbNcmg4XeNuC2pb1e5B2XcYotU/1Ex72uY7TSTKu1PvVawMjuDedz9gGGbBE62ZdHOzQ3H2r/4IE3Ljl5zeyzZPyzKEivtejVrVY7V" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=debug msg="completed keyexchange" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=debug msg="completed challenge" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=info msg="authenticated AP" username="11*******00" Oct 13 22:10:03 volumio go-librespot[8843]: time="2026-10-13T22:10:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 13 22:10:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 13 22:10:03 volumio volumio[1414]: info: Connection to go-librespot Websocket closed Oct 13 22:10:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 13 22:10:04 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::ClearQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::stop Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::clearPlayQueue Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::updateTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPlay Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::play index 3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::stop Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::play index undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::updateTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:04 volumio volumio[1414]: info: [1791925804276] ControllerSpotify::clearAddPlayTrack Oct 13 22:10:04 volumio volumio[1414]: info: Sending Spotify command with payload to local API: /player/play Oct 13 22:10:04 volumio volumio[1414]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:10:04 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::ClearQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::stop Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::clearPlayQueue Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1060gzllf4b0UETAXisR5l Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2iXoZ4qfHQ4zfXpLo5RH0m Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6fi18o5Jp6dAIcXPlgzhWF Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5fpj84RsT9cOTfWUCxBTXu Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::updateTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPlay Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::play index 3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::addQueueItems Oct 13 22:10:04 volumio volumio[1414]: info: Preload queue cleared Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6voIJ7OWwRabSZDC77D5Hp Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1INksmStBpxdNlBzwGUvsg Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3MjrueDQKVr6xDDseZwhEd Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:102zQx1snTXKKFSYRN2bsT Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0uObHujoqoTLLiA6FTslbq Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:44zR1haEFzUOkNx2oNcDPx Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0m9pyxMQlPXjH21HVGerdf Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2s2SoKsM8E7WKtC2PJGLTl Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0Wu0ngacZJaLFQ1J54C0m1 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0oRU4EvJPm62mu2pHej5Gd Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6yVYFlUSFQolLOCKD4oFe3 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3ra7fDLqfUHBWBL702fOAn Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:32Q9Vj540kLQrjQJxTVhL6 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1dYERGH5j9DqDcqgVoj9Rd Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2CmCUBIOVFNswRHaV8N77c Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2GikowG1krEbxgwuXI3fSg Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0yC5UuQoxpw8j05SH8jhnX Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5vIpkzvNbXlknNlXAF2rcc Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3dZTrUqLpivFUkSuIpSoyb Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5DkFBDxHIFa7cj3oyqKxFa Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4HQyX6OyDXNus0f7aPrHpw Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4g3x8k65lqP9bRUvqD1oL0 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0LRJx6EKkr2e5GmZYRe9Bj Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4UbC0NniHL5o5Wt6CMttb6 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:7iiNvEGq7WQarP2cngclRE Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0rFEAWKMih1r6l9ZydkvX2 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3TLpGYpiakD3iJYCveeyGP Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4iej0znQnZAym0PgU7PICD Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6NWQ1Nsp49L6e9eBvcIf5Y Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0ZhN4Ut2wgb0X4603DuCEP Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0uZ0Ec6FkEwHGRhrtvcJAo Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:1AdaAAPN5ggOTBh9hW4qie Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0PpwkRqCbwyY3sCSBuZM0y Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:68QotiFmMAThnuyhwgcwIB Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:6wqRmSvHbSdD66iSaDA4ap Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5mdHGc62AEqK11GAUozg3t Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:4CfMafMHXNqAEKcmh39mES Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:52zWuwIaQb2wcPVsM143wP Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0DBoPzmkOR9XdAUy3RgvtH Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2hpQOXEBsZGsN1o0K6zna1 Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0lFYsvuAsbUaW73DERGo8x Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:3MeFWVniBG4McV0nwNonrl Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:0RZTK66wRdfMMQFw8afAEU Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:5pvXidnXBgzfllwfWR3UNE Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:2O1ElYUg86yy9diVhGddqD Oct 13 22:10:04 volumio volumio[1414]: info: Adding Item to queue: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:10:04 volumio volumio[1414]: info: Using cached record of: spotify:track:64q8nvkJGFyFR00cbtEjr3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::stop Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreCommandRouter::volumioPushQueue Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::saveQueue Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::play index undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::updateTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrackBlock Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:04 volumio volumio[1414]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:10:04 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:04 volumio volumio[1414]: info: [1791925804472] ControllerSpotify::clearAddPlayTrack Oct 13 22:10:04 volumio volumio[1414]: info: Sending Spotify command with payload to local API: /player/play Oct 13 22:10:04 volumio volumio[1414]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:10:05 volumio volumio[1414]: info: CoreCommandRouter::volumioGetState Oct 13 22:10:05 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:05 volumio volumio[1414]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 13 22:10:05 volumio volumio[1414]: info: CoreCommandRouter::volumioPlay Oct 13 22:10:05 volumio volumio[1414]: info: CoreStateMachine::play index undefined Oct 13 22:10:05 volumio volumio[1414]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 22:10:05 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:05 volumio volumio[1414]: info: CoreStateMachine::startPlaybackTimer Oct 13 22:10:05 volumio volumio[1414]: info: CorePlayQueue::getTrack 3 Oct 13 22:10:05 volumio volumio[1414]: info: [1791925805480] ControllerSpotify::clearAddPlayTrack Oct 13 22:10:05 volumio volumio[1414]: info: Sending Spotify command with payload to local API: /player/play Oct 13 22:10:05 volumio volumio[1414]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:10:05 volumio volumio[1414]: info: Getting Spotify volume Oct 13 22:10:05 volumio volumio[1414]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 22:10:05 volumio volumio[1414]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 13 22:10:05 volumio volumio[1414]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 13 22:10:05 volumio volumio[1414]: errno: -111, Oct 13 22:10:05 volumio volumio[1414]: code: 'ECONNREFUSED', Oct 13 22:10:05 volumio volumio[1414]: syscall: 'connect', Oct 13 22:10:05 volumio volumio[1414]: address: '127.0.0.1', Oct 13 22:10:05 volumio volumio[1414]: port: 9879, Oct 13 22:10:05 volumio volumio[1414]: response: undefined Oct 13 22:10:05 volumio volumio[1414]: } Oct 13 22:10:05 volumio volumio[1414]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 22:10:06 volumio sudo[8867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 22:09' Oct 13 22:10:06 volumio sudo[8867]: 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="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"