Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.646+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.660+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=14.278299ms Aug 30 22:42:21 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:42:21 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:42:21 volumio-black volumio[1280]: info: Discovery: Getting this device information Aug 30 22:42:21 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:42:21 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:42:21 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.676+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=28.346283ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.682+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=34.454107ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.695+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=http://pushupdates.volumio.org duration=46.698995ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.707+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://google.com duration=60.761036ms Aug 30 22:42:21 volumio-black volumio[1280]: verbose: New Socket.io Connection to 192.168.1.44:3000 from 192.168.1.56 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Aug 30 22:42:21 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 22:42:21 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.756+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://www.googleapis.com duration=109.93916ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.775+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=128.488778ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.775+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://securetoken.googleapis.com duration=128.824275ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.788+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://functions.volumio.cloud duration=140.735388ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.790+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://database.volumio.cloud duration=142.133989ms Aug 30 22:42:21 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:21.795+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=https://functions.volumio.cloud duration=148.422848ms Aug 30 22:42:22 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:22.036+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=http://cddb.volumio.org duration=388.320515ms Aug 30 22:42:22 volumio-black volumio5-onboarding[1684]: time=2026-08-30T22:42:22.123+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.56:32916,00:00:00:00:00:00%01 @ 0x1ca8b10" latency=-850.624268ms timeout=10s endpoint=http://plugins.volumio.org duration=475.015427ms Aug 30 22:43:16 volumio-black volumio[1280]: info: Received OAUTH Data Aug 30 22:43:16 volumio-black volumio[1280]: info: Executing Spotify Oauth Login Aug 30 22:43:16 volumio-black volumio[1280]: info: Saving Spotify Refresh Token Aug 30 22:43:16 volumio-black volumio[1280]: info: New Spotify access tokenBQAvOc9SLl... Aug 30 22:43:16 volumio-black volumio[1280]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 30 22:43:16 volumio-black volumio[1280]: SPOTIFY: User informations: {"account_id":"HlaxBDUgEf","country":"CH","display_name":"Basil","email":"basil.muehlemann@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/basil85"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/basil85","id":"basil85","images":[],"product":"premium","type":"user","uri":"spotify:user:basil85"} Aug 30 22:43:16 volumio-black volumio[1280]: info: Creating Spotify config file Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 22:43:16 volumio-black volumio[1280]: info: Spotify config file written Aug 30 22:43:16 volumio-black sudo[3081]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 22:43:16 volumio-black sudo[3077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 30 22:43:16 volumio-black sudo[3081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 22:43:16 volumio-black sudo[3077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 22:43:16 volumio-black sudo[3079]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 22:43:16 volumio-black sudo[3079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 22:43:16 volumio-black sudo[3081]: pam_unix(sudo:session): session closed for user root Aug 30 22:43:16 volumio-black sudo[3079]: pam_unix(sudo:session): session closed for user root Aug 30 22:43:16 volumio-black systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 22:43:16 volumio-black systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 30 22:43:16 volumio-black volumio[1280]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.56 UA: Mozilla/5.0 (Linux; Android 17; Pixel 9 Pro Build/CP2A.260805.005; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 30 22:43:16 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:16 volumio-black sudo[3077]: pam_unix(sudo:session): session closed for user root Aug 30 22:43:16 volumio-black go-librespot[3085]: go-librespot daemon starting... Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=debug msg="app state loaded" Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:16 volumio-black volumio[1280]: info: New Spotify access tokenBQCwSNrLKH... Aug 30 22:43:16 volumio-black volumio[1280]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:43:16 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 22:43:16 volumio-black volumio[1280]: info: Received Get System Info Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:43:16 volumio-black volumio[1280]: info: Discovery: Getting this device information Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:43:16 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:43:16 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:16 volumio-black volumio[1280]: info: Listing playlists Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 22:43:16 volumio-black volumio[1280]: SPOTIFY: User informations: {"account_id":"HlaxBDUgEf","country":"CH","display_name":"Basil","email":"basil.muehlemann@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/basil85"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/basil85","id":"basil85","images":[],"product":"premium","type":"user","uri":"spotify:user:basil85"} Aug 30 22:43:16 volumio-black volumio[1280]: info: Spotify Successfully logged in Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 22:43:16 volumio-black volumio[1280]: info: [1788122596871] CoreMusicLibrary::Adding element Spotify Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 22:43:16 volumio-black volumio[1280]: Cannot find translation for source Spotify Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=info msg="zeroconf server listening on port 33027" Aug 30 22:43:16 volumio-black go-librespot[3086]: time="2026-08-30T22:43:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:16 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 22:43:17 volumio-black go-librespot[3086]: time="2026-08-30T22:43:17+02:00" level=debug msg="obtained new client token: AAGEUDC5SQeNqtjnuC4C4ENEG5hkCGF+czNB8OMHVc5tsRqiiJ4B3Eq3Bf0VeKmPee7INeRWm89lvPA6GliW9shmEQNY6pZnru9GkLV87qdqN00mm54L+7+OmH72E0Zpl+h96cHBBdxa2rTyGvB2asc7Pxfsoz6hDOjAK4n0qf7gAp7sB7H5tt9+vUBUh7i9O08xjb0UaM+wZFCGsFz2F8LxsoB6f228DkrqF+fsx0BwNVni3/e4mg==" Aug 30 22:43:17 volumio-black go-librespot[3086]: time="2026-08-30T22:43:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:17 volumio-black go-librespot[3086]: time="2026-08-30T22:43:17+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:17 volumio-black go-librespot[3086]: time="2026-08-30T22:43:17+02:00" level=debug msg="completed challenge" Aug 30 22:43:17 volumio-black go-librespot[3086]: time="2026-08-30T22:43:17+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:17 volumio-black go-librespot[3086]: time="2026-08-30T22:43:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:17 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:17 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:18 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 22:43:18 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 22:43:18 volumio-black volumio[1280]: info: Received Get System Info Aug 30 22:43:18 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:43:18 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:43:18 volumio-black volumio[1280]: info: Discovery: Getting this device information Aug 30 22:43:18 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:43:18 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:18 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:43:19 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 22:43:19 volumio-black volumio[1280]: info: Received Get System Info Aug 30 22:43:19 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:43:19 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:43:19 volumio-black volumio[1280]: info: Discovery: Getting this device information Aug 30 22:43:19 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:43:19 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:19 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:43:19 volumio-black volumio[1280]: info: go-librespot daemon successfully initialized Aug 30 22:43:20 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 30 22:43:20 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:20 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:20 volumio-black go-librespot[3095]: go-librespot daemon starting... Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="app state loaded" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=info msg="zeroconf server listening on port 34875" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="obtained new client token: AAFqrIMqF1ZpX1fz+1h8kD1ek7SZrSX/OqvfKyb1jwIzQPqtLSwdIZ0JL6dwRomdSmQDffKGXZd2tqxRX1KrN+MaCL+00V+KaMrIforMKHHw2dTHQtxqo3y+unGHwlWEqVzF3REdBuKuB/SkDyS5MFIy8ZjpZf5N/kR40RGbkZrZ/bQpfYOgiSP8lMQ92bypX9jLZCz37ID2005Q4EjcdTkpVT78LstN+Lo0zjBMfloMUR4/tEzryuhd" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=debug msg="completed challenge" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:20 volumio-black go-librespot[3096]: time="2026-08-30T22:43:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:20 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:20 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:22 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:22 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:23 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetQueue Aug 30 22:43:23 volumio-black volumio[1280]: info: CoreStateMachine::getQueue Aug 30 22:43:23 volumio-black volumio[1280]: info: CorePlayQueue::getQueue Aug 30 22:43:24 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 30 22:43:24 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:24 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:24 volumio-black go-librespot[3110]: go-librespot daemon starting... Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="app state loaded" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=info msg="zeroconf server listening on port 39701" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="obtained new client token: AAHVxNBNwixaHsdQdN649b+zdmeKK6kK53aDOtoIBGI84dYKcXLYctk4vUPKZJF45vRAzWtocEGTQqDx4ZfKu2DQ8UycSoOijxXoPoVARXBSFGRrOI9gfRCTIQDk/Y/nFKah/j/NMYGc7H6ux1T2GOY5r6wMxPhGGpwHPl/GF7FJl4kU4YOOqc13FoCqlz11TpF+tpS7NfYgYOiMlQotGv+onk26+PD2MeT0MyPeqBrJnFwFAgQrufSA" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=debug msg="completed challenge" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:24 volumio-black go-librespot[3111]: time="2026-08-30T22:43:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:24 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:24 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:24 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 30 22:43:24 volumio-black volumio[1280]: info: In handleBrowseUri, curUri=spotify Aug 30 22:43:24 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:24 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:24 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:24 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:25 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:25 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:26 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 30 22:43:26 volumio-black volumio[1280]: info: In handleBrowseUri, curUri=spotify/playlists Aug 30 22:43:26 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:26 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 22:43:27 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 30 22:43:27 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:27 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:27 volumio-black go-librespot[3134]: go-librespot daemon starting... Aug 30 22:43:27 volumio-black go-librespot[3135]: time="2026-08-30T22:43:27+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:27 volumio-black go-librespot[3135]: time="2026-08-30T22:43:27+02:00" level=debug msg="app state loaded" Aug 30 22:43:27 volumio-black go-librespot[3135]: time="2026-08-30T22:43:27+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=info msg="zeroconf server listening on port 40789" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="obtained new client token: AAE54Flr5kal4YT6tZhk09GcvvyopQEOAlJQr3YnUGKyuG0b15ivyYeomrGuLCW50C/MTXQVQRuwI8J9AncdN7BVLcQXQAY1klsMQqoZTTiw0xMCdLUriNPaivAJ8s7FTl0V6pceLHFtdHD9AqQqKjXnL1jlJZhcD+pVhRmRyEeK/cNcqeovBJov0X8Zn75/gWsoIqxbDfN//fSZvGC3SSugGH5fvnZnq3BnW/X/w+MOnm1bQrShLg==" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=debug msg="completed challenge" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:28 volumio-black go-librespot[3135]: time="2026-08-30T22:43:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:28 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:28 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:28 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:28 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:29 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 30 22:43:29 volumio-black volumio[1280]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:1fFN6FqTrM0jDGYhhYhakk Aug 30 22:43:30 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:30 volumio-black volumio[1280]: info: Preloading song: spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:3Y207UZ4wUCAzOWUrHoZ8a in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:12bYYQaLqHliSXvRIYlq8G in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:0AZTpwagcDe4nH0mPcnYhS in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Y207UZ4wUCAzOWUrHoZ8a","service":"spop","name":"Valerie - Live","artist":"Amy Winehouse","album":"Back To Black","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b273eb58cc91f740bda393b88b34","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:4bNer4Cz6chRVexe3Z8hJv in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:12bYYQaLqHliSXvRIYlq8G","service":"spop","name":"I Just Might","artist":"Bruno Mars","album":"I Just Might","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7623a0a60f330ee2b24d498","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:5fMInRpERS8jP2zPz6QyUI in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0AZTpwagcDe4nH0mPcnYhS","service":"spop","name":"Musta Been a Ghost","artist":"Próxima Parada","album":"Kind Reminder","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273f53b1e3167f82ceab593d370","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4bNer4Cz6chRVexe3Z8hJv","service":"spop","name":"Know You Better","artist":"Black Pumas","album":"Black Pumas","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b273975cd7f3dbcbb6f713de7e7b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:0FnVJjLJPRHgOWxucza08b in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5fMInRpERS8jP2zPz6QyUI","service":"spop","name":"Love And Happiness","artist":"Marc Broussard","album":"S.O.S.: Save Our Soul","type":"song","duration":278,"albumart":"https://i.scdn.co/image/ab67616d0000b2738b3549b545a36b38aaa8d40c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:1vtrfEsKAdbJfuHtddCAMe in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:30 volumio-black volumio[1280]: info: Exploding uri spotify:track:1OzFIbfhy8LdcbX9TJKj82 in service spop Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:30 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0FnVJjLJPRHgOWxucza08b","service":"spop","name":"What Man Have Done","artist":"Noora Noor","album":"Soul Deep","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273f376212dd8b495e45c1348b1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:2JRIqAEID7y4PUKZhJBPha in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1vtrfEsKAdbJfuHtddCAMe","service":"spop","name":"Mystery ft. PJ Morton","artist":"Allen Stone","album":"Mystery ft. PJ Morton","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b27392106b4514b112c4a833e9b8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:3fqwjXwUGN6vbzIwvyFMhx in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1OzFIbfhy8LdcbX9TJKj82","service":"spop","name":"Just a Kiss Away","artist":"Nils Landgren Funk Unit","album":"Unbreakable","type":"song","duration":320,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b6ec456c2f287d09342e846","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:3QKPcyXFQ5IudDOpdIZRj3 in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:4DI9D4LZjQZXBHGFeEAdmc in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2JRIqAEID7y4PUKZhJBPha","service":"spop","name":"Wenn du magst","artist":"Fräulein Luise","album":"Kleine Freiheit","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b273fca8e3bf893e52d3c0cf6ed9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:1FqOGWc280BYbpRwLiPn4P in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4DI9D4LZjQZXBHGFeEAdmc","service":"spop","name":"While You're Out Looking For Sugar","artist":"Joss Stone","album":"The Soul Sessions, Vol. 2 (Deluxe Edition)","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273938a86462234aae4fdbfe369","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3QKPcyXFQ5IudDOpdIZRj3","service":"spop","name":"Kung Fu Fighting","artist":"Shaolin Temple Defenders","album":"From the Inside","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b273af380d938bccbaa7cf1cb8c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:2g0QvZOAlXf8nyu592CgsN in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:5heR4UdIdf2ZC5C7vJGbE9 in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1FqOGWc280BYbpRwLiPn4P","service":"spop","name":"Man Of The Universe","artist":"The Teskey Brothers","album":"Run Home Slow","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2739db8c4377a304b8d9877bb09","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2g0QvZOAlXf8nyu592CgsN","service":"spop","name":"I Want To Know","artist":"Raphael Wressnig","album":"Soul Gumbo","type":"song","duration":430,"albumart":"https://i.scdn.co/image/ab67616d0000b27301d3fa072a5ec3082b838c4c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3fqwjXwUGN6vbzIwvyFMhx","service":"spop","name":"Tennessee Whiskey","artist":"Chris Stapleton","album":"Traveller","type":"song","duration":293,"albumart":"https://i.scdn.co/image/ab67616d0000b273540fc1d083eac5bcff8dad21","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:0dMd4rilfd6gPbXaLpNYhu in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:6J3HNhDBIwEX11bX8hzx5u in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5heR4UdIdf2ZC5C7vJGbE9","service":"spop","name":"Musiq","artist":"Noora Noor","album":"Soul Deep","type":"song","duration":422,"albumart":"https://i.scdn.co/image/ab67616d0000b273f376212dd8b495e45c1348b1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:6uSuDZyZminauHXtQNMFen in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0dMd4rilfd6gPbXaLpNYhu","service":"spop","name":"Higher Ground","artist":"Stevie Wonder","album":"Innervisions","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273ea8ab1a548312b79ac955266","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6J3HNhDBIwEX11bX8hzx5u","service":"spop","name":"Colors","artist":"Black Pumas","album":"Black Pumas","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b273975cd7f3dbcbb6f713de7e7b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:1xQUAAiSpe8JxlQC77klij in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6uSuDZyZminauHXtQNMFen","service":"spop","name":"The Cost Of Freedom","artist":"Marla Glen","album":"This Is Marla Glen 9 Tracks","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b27311c23ba48e311482fa36faf6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:2oMfqntin1tncbli85BmnO in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1xQUAAiSpe8JxlQC77klij","service":"spop","name":"Lean on Me","artist":"Martha High","album":"Singing for the Good Times","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b273360cece4738c80d694506def","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:4WIBvQtm11KmyTVExfHM1b in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:0yOAmAgOpO9Jss3ZzQrf77 in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:31 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 30 22:43:31 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:5IjvNjAe9ZoZ322IBWvJBe in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4WIBvQtm11KmyTVExfHM1b","service":"spop","name":"Let Me Let You Down","artist":"The Teskey Brothers","album":"Run Home Slow","type":"song","duration":253,"albumart":"https://i.scdn.co/image/ab67616d0000b2739db8c4377a304b8d9877bb09","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2oMfqntin1tncbli85BmnO","service":"spop","name":"Running Away","artist":"Shaolin Temple Defenders","album":"Free Your Soul","type":"song","duration":286,"albumart":"https://i.scdn.co/image/ab67616d0000b273042093f28fa660a2874ac347","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:31 volumio-black go-librespot[3145]: go-librespot daemon starting... Aug 30 22:43:31 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:31 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0yOAmAgOpO9Jss3ZzQrf77","service":"spop","name":"I'd Rather Go Blind","artist":"Etta James","album":"Burnin' Down The House","type":"song","duration":380,"albumart":"https://i.scdn.co/image/ab67616d0000b273299ba12a268983770b76c045","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:7o23a9OJut6JsgW3JEwFUn in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=debug msg="app state loaded" Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:6YfMoXrlPZGoYzTJ8Svfgs in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5IjvNjAe9ZoZ322IBWvJBe","service":"spop","name":"Harry Hippie","artist":"Marc Broussard","album":"S.O.S.: Save Our Soul","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b2738b3549b545a36b38aaa8d40c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:4N0TP4Rmj6QQezWV88ARNJ in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:4UX7OpafcTGey8CdA5zJkH in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7o23a9OJut6JsgW3JEwFUn","service":"spop","name":"Get The Money","artist":"Raphael Wressnig","album":"Soul Gumbo","type":"song","duration":437,"albumart":"https://i.scdn.co/image/ab67616d0000b27301d3fa072a5ec3082b838c4c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6YfMoXrlPZGoYzTJ8Svfgs","service":"spop","name":"Running Out of Lies","artist":"Etta James","album":"Life, Love & The Blues","type":"song","duration":303,"albumart":"https://i.scdn.co/image/ab67616d0000b273dbe323706c65949e50663dd3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=info msg="zeroconf server listening on port 42883" Aug 30 22:43:31 volumio-black go-librespot[3146]: time="2026-08-30T22:43:31+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4N0TP4Rmj6QQezWV88ARNJ","service":"spop","name":"Superstition","artist":"Stevie Wonder","album":"Talking Book","type":"song","duration":266,"albumart":"https://i.scdn.co/image/ab67616d0000b273a14b08b9a6616e121df5e8b0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:31 volumio-black volumio[1280]: info: Exploding uri spotify:track:259d4MYHAadyTsY5pelMz8 in service spop Aug 30 22:43:31 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:32 volumio-black go-librespot[3146]: time="2026-08-30T22:43:32+02:00" level=debug msg="obtained new client token: AAHzOEsUlOqIFUR4EB0ITHnk13VdYS6PHR6/4T26iOulWtGNXKOx6GwPMcvTUALAKxB+eL9jQNdxazpKAohCsVQiUpbx6YoamfF4IkUny6WNTUx6Jj85UHXIfodtiLdOHwR9kOgEfzfmeIjfddgtFZLDhguvapmURvZchAOdPE9wQK166pnc3B8m7tR7KuMRtNn7rF6daqJpn/u8uyz61t6twxGWWbrfVMCCj7ZvXW7QzuOIF3ksc4JG" Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4UX7OpafcTGey8CdA5zJkH","service":"spop","name":"You Need A Woman Like Me","artist":"Martha High","album":"It's High Time","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b2734049c5d8896a5adcccf59f78","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:1RmJJPvyMSszOQHJ4s03A2 in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:32 volumio-black go-librespot[3146]: time="2026-08-30T22:43:32+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:259d4MYHAadyTsY5pelMz8","service":"spop","name":"Pain And Misery","artist":"The Teskey Brothers","album":"Half Mile Harvest","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b273117a2decdbfd1b885a66d1bb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:0DIcHQUp4f9j4Gym2Kn8nm in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:32 volumio-black go-librespot[3146]: time="2026-08-30T22:43:32+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:32 volumio-black go-librespot[3146]: time="2026-08-30T22:43:32+02:00" level=debug msg="completed challenge" Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:4LpFfd70HLdNIoVXZplfxH in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:32 volumio-black go-librespot[3146]: time="2026-08-30T22:43:32+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1RmJJPvyMSszOQHJ4s03A2","service":"spop","name":"Call Me","artist":"St. Paul & The Broken Bones","album":"Half The City","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b273deaa6319f65fa6df1c4e9047","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:10PzBYcWZEUbwZTC2844G3 in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0DIcHQUp4f9j4Gym2Kn8nm","service":"spop","name":"Souvenir","artist":"Shawn Lee","album":"Bless My Soul","type":"song","duration":226,"albumart":"https://i.scdn.co/image/ab67616d0000b2735679696252229bf299101707","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:3U7eywWAUyKxVLEPeEzOqc in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:32 volumio-black go-librespot[3146]: time="2026-08-30T22:43:32+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:32 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:32 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4LpFfd70HLdNIoVXZplfxH","service":"spop","name":"Precious Love","artist":"Lee Fields & The Expressions","album":"Special Night","type":"song","duration":278,"albumart":"https://i.scdn.co/image/ab67616d0000b27394ecfaa75aff2b60389fda76","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:10PzBYcWZEUbwZTC2844G3","service":"spop","name":"Good Love","artist":"Mamas Gun","album":"Cure the Jones","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b2731eeb12315c21a208b1eca4ae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:6kIkjkxY2bp0PTSWpXl0mR in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3U7eywWAUyKxVLEPeEzOqc","service":"spop","name":"Running Away","artist":"Shaolin Temple Defenders","album":"Free Your Soul","type":"song","duration":286,"albumart":"https://i.scdn.co/image/ab67616d0000b273142914290fffac29af80b888","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:697lRzBkfPzmgFTj3PKMRK in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6kIkjkxY2bp0PTSWpXl0mR","service":"spop","name":"What About You","artist":"Shaolin Temple Defenders","album":"Free Your Soul","type":"song","duration":248,"albumart":"https://i.scdn.co/image/ab67616d0000b273142914290fffac29af80b888","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:1AWz890n0HfGIucJa0RzQ1 in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:1srQrPOCvEGRn5kScgDnTv in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1AWz890n0HfGIucJa0RzQ1","service":"spop","name":"Take Me Back","artist":"Sugaray Rayford","album":"The World That We Live In","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b2738d118f6c922bcc0033bb1861","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:697lRzBkfPzmgFTj3PKMRK","service":"spop","name":"The Reign","artist":"Shaolin Temple Defenders","album":"Free Your Soul","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273142914290fffac29af80b888","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:5DflVzAvAHxlJCieAAGCnn in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:2qRC8UXceLoMTP5E1QDhKK in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1srQrPOCvEGRn5kScgDnTv","service":"spop","name":"Time to Get Movin'","artist":"Sugaray Rayford","album":"Somebody Save Me","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c84c7062e20e34e5b17eb71","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:4lLbzbeP6JD15H4RnfSiA2 in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DflVzAvAHxlJCieAAGCnn","service":"spop","name":"Run For Cover","artist":"Sugaray Rayford","album":"Human Decency","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b2733b87fb96bf70fe1795ec1fe5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:6uXaAlQDX6TdqiQFCQDTYw in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2qRC8UXceLoMTP5E1QDhKK","service":"spop","name":"Super Nacho Funk","artist":"Shaolin Temple Defenders","album":"From the Inside","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273af380d938bccbaa7cf1cb8c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:4YAhuS6q8JebD31AWxsDvT in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6uXaAlQDX6TdqiQFCQDTYw","service":"spop","name":"I'm Gonna Be Fine","artist":"Shaolin Temple Defenders","album":"From the Inside","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273af380d938bccbaa7cf1cb8c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:6nLvaCZFR1wEzW3sIKpsnr in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4YAhuS6q8JebD31AWxsDvT","service":"spop","name":"A Lil' Bit of Lovin'","artist":"Shaolin Temple Defenders","album":"From the Inside","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b273af380d938bccbaa7cf1cb8c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:4faJXHXhki6LppYQjpA0hG in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:0SEx1KQmfdesGopGQi4iv8 in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:track:5MxYNnD7kTJmJWSQvomDVO in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:32 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:32 volumio-black volumio[1280]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:43:32 volumio-black volumio[1280]: info: CoreStateMachine::ClearQueue Aug 30 22:43:32 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:32 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:32 volumio-black volumio[1280]: info: CorePlayQueue::clearPlayQueue Aug 30 22:43:32 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:32 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:32 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:32 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:32 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:32 volumio-black volumio[1280]: info: Adding Item to queue: spotify:user:spotify:playlist:1fFN6FqTrM0jDGYhhYhakk Aug 30 22:43:32 volumio-black volumio[1280]: info: Exploding uri spotify:user:spotify:playlist:1fFN6FqTrM0jDGYhhYhakk in service spop Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:1fFN6FqTrM0jDGYhhYhakk Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4faJXHXhki6LppYQjpA0hG","service":"spop","name":"Autumn Leaves","artist":"Monks Road Social","album":"Out of Bounds","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b273831b2b82aa03e43a9291c903","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4lLbzbeP6JD15H4RnfSiA2","service":"spop","name":"It's So Easy","artist":"Shaolin Temple Defenders","album":"From the Inside","type":"song","duration":248,"albumart":"https://i.scdn.co/image/ab67616d0000b273af380d938bccbaa7cf1cb8c5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0SEx1KQmfdesGopGQi4iv8","service":"spop","name":"Mr. Wrong","artist":"Ruby Velle & The Soulphonics","album":"It's About Time","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b273c91a6c38f3fccef6b44ba3e6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:32 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6nLvaCZFR1wEzW3sIKpsnr","service":"spop","name":"Valerie - Live At BBC Radio 1 Live Lounge, London / 2007","artist":"Amy Winehouse","album":"Back To Black (Deluxe Edition)","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b27376ffb5b5ab045d22c81235c1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:33 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5MxYNnD7kTJmJWSQvomDVO","service":"spop","name":"Lay Away","artist":"Nigel Hall","album":"Ladies & Gentlemen... Nigel Hall","type":"song","duration":355,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b360f9c9d20cb4ed0699681","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:33 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:33 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPlay Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::play index 0 Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::play index undefined Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:33 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:33 volumio-black volumio[1280]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:43:33 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:33 volumio-black volumio[1280]: info: [1788122613400] ControllerSpotify::clearAddPlayTrack Aug 30 22:43:33 volumio-black volumio[1280]: info: Sending Spotify command with payload to local API: /player/play Aug 30 22:43:33 volumio-black volumio[1280]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:34 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:34 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 22:43:34 volumio-black volumio[1280]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 22:43:34 volumio-black volumio[1280]: info: Received Get System Version Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 22:43:34 volumio-black volumio[1280]: info: Received Get System Info Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 22:43:34 volumio-black volumio[1280]: info: Discovery: Getting this device information Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::volumioGetState Aug 30 22:43:34 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:34 volumio-black volumio[1280]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 22:43:35 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 30 22:43:35 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:35 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:35 volumio-black go-librespot[3169]: go-librespot daemon starting... Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="app state loaded" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=info msg="zeroconf server listening on port 42159" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="obtained new client token: AAGqLX6dJXqmhgUpgRN+4ZVkiU7IsQ5J75fcUL0/dWuAVkT/n2HqE2BXMB2a+ISYBNHQ8m2wzwvJf1pFmo54bmO+o2BM9kc1MbcV6q5aJEIX5GyI8rL9YEWka8mX1e6Kgo/qfyIAN4IakQhxR8yeaP4miox3js2ZNutf8QYDE0BnzmtCO2WgCqR5MMCfrQe8fzGKIXUowaTUU3a8vRXinkpRpETtarTwAN+E2sfiirPi/NV0dJscD+od" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=debug msg="completed challenge" Aug 30 22:43:35 volumio-black go-librespot[3170]: time="2026-08-30T22:43:35+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:36 volumio-black go-librespot[3170]: time="2026-08-30T22:43:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:36 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:36 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:37 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::ClearQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::clearPlayQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:37 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPlay Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::play index 0 Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:37 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:37 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:2uKt3YMVIDuEDY2xH1jZFz in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:3uJ7YB1zCQH0Y49sd5t1hq in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:58gPIRzKQteZDwUQKQAj0q in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:4gi81cgaU0Xmek1IuOENM5 in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:4GUaG3df9nbrj77GKEB76j in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:3PbcV8c3f6ZM8P7hyyxX2l in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:00Sx0hkJQi0sZgvcDxaCdu in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:5q4N14cDSyNaz5PzSu971y in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:0ad1nLYLzBf0uSPxjCpZJi in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:3ETWwN2Fa8ihTN2LKpryV2 in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:0RNRRdxkgkMdUYWwOcRoIs in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:37 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:37 volumio-black volumio[1280]: info: Exploding uri spotify:track:66jUX8GhpqIEaEWIimYuie in service spop Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: EXPLODING URI:spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::play index undefined Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:37 volumio-black volumio[1280]: info: [1788122617118] ControllerSpotify::clearAddPlayTrack Aug 30 22:43:37 volumio-black volumio[1280]: info: Sending Spotify command with payload to local API: /player/play Aug 30 22:43:37 volumio-black volumio[1280]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2uKt3YMVIDuEDY2xH1jZFz","service":"spop","name":"Shake It","artist":"Robert Randolph & The Family Band","album":"Got Soul","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b2739e9f5a0774bbd4d760f25b02","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3uJ7YB1zCQH0Y49sd5t1hq","service":"spop","name":"Believe It When You See It","artist":"Doctorfunk","album":"Second Opinion","type":"song","duration":348,"albumart":"https://i.scdn.co/image/ab67616d0000b27370562f96ab1e1404df36a808","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5q4N14cDSyNaz5PzSu971y","service":"spop","name":"Consider Me","artist":"Allen Stone","album":"Building Balance","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273681c1995286b24dc807b788c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4gi81cgaU0Xmek1IuOENM5","service":"spop","name":"Rita Is Gone","artist":"The Marcus King Band","album":"The Marcus King Band","type":"song","duration":265,"albumart":"https://i.scdn.co/image/ab67616d0000b2737077a50b64493bf049881216","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk","service":"spop","name":"Are You Ready to Love Me?","artist":"The War And Treaty","album":"Healing Tide","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b2732c052a73465a285f21e0de9e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3PbcV8c3f6ZM8P7hyyxX2l","service":"spop","name":"Can’t Explain This Love","artist":"Allen Stone","album":"Mystery ft. PJ Morton","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b27392106b4514b112c4a833e9b8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ad1nLYLzBf0uSPxjCpZJi","service":"spop","name":"Lonely Night in Georgia - Live at Dockside Studio","artist":"Marc Broussard","album":"Home (The Dockside Sessions)","type":"song","duration":373,"albumart":"https://i.scdn.co/image/ab67616d0000b27391469805828929384b10d8a6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4GUaG3df9nbrj77GKEB76j","service":"spop","name":"Forever You and Me","artist":"The Teskey Brothers","album":"Forever You and Me","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273958fdcc3cffcbeac8ccbc818","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:00Sx0hkJQi0sZgvcDxaCdu","service":"spop","name":"Take My Heart","artist":"The Teskey Brothers","album":"The Winding Way","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b27367f540e2f2ca8c98750c1015","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ETWwN2Fa8ihTN2LKpryV2","service":"spop","name":"Last Forever","artist":"Shanice Green","album":"Last Forever","type":"song","duration":226,"albumart":"https://i.scdn.co/image/ab67616d0000b273be0ea6859dffb3bed6f38ea4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0RNRRdxkgkMdUYWwOcRoIs","service":"spop","name":"Pass Me By","artist":"Sharon Jones & The Dap-Kings","album":"Soul of a Woman","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b273e934e34550506789bc119a46","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:66jUX8GhpqIEaEWIimYuie","service":"spop","name":"Upside","artist":"Allen Stone","album":"Radius (Deluxe Edition)","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b2734d869afb2810b578e594fda9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:58gPIRzKQteZDwUQKQAj0q","service":"spop","name":"It's Gonna Rain","artist":"The Souljazz Orchestra","album":"Resistance","type":"song","duration":307,"albumart":"https://i.scdn.co/image/ab67616d0000b27350577d18165a235815e1e6d6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:37 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:37 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:37 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:37 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:39 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 30 22:43:39 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:39 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:39 volumio-black go-librespot[3185]: go-librespot daemon starting... Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="app state loaded" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=info msg="zeroconf server listening on port 45943" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="obtained new client token: AAE67UJLyUoh0bCuq6fQ6f96FMa9BfNnC9hJnsTtCEA5pcSILbiWnx2CCKdBvJBMS3TZu2hfThhRO3QMqk7CjM19++AD3vw6kgYh2Nn7nw/6Oiu9aa/oAMlYXv0cduO60TC3yk2W4cGlxRkRMeoWkWVZY89daHWV//SCglwkVa1LqDbfPCrPxUKhYuuD19jaQk5Dkh31zWqT0PKQMl8abVa1BiB8/raTFk+bhjCnpIErq/eN1GvZzzkO" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=debug msg="completed challenge" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:39 volumio-black go-librespot[3186]: time="2026-08-30T22:43:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:39 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:39 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:40 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:40 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:41 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::ClearQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::clearPlayQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:41 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPlay Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::play index 1 Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:41 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:41 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:41 volumio-black volumio[1280]: info: Using cached record of: spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::play index undefined Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 1 Aug 30 22:43:41 volumio-black volumio[1280]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:43:41 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 1 Aug 30 22:43:41 volumio-black volumio[1280]: info: [1788122621465] ControllerSpotify::clearAddPlayTrack Aug 30 22:43:41 volumio-black volumio[1280]: info: Sending Spotify command with payload to local API: /player/play Aug 30 22:43:41 volumio-black volumio[1280]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:42 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 30 22:43:42 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:42 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:42 volumio-black go-librespot[3196]: go-librespot daemon starting... Aug 30 22:43:42 volumio-black go-librespot[3197]: time="2026-08-30T22:43:42+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:42 volumio-black go-librespot[3197]: time="2026-08-30T22:43:42+02:00" level=debug msg="app state loaded" Aug 30 22:43:42 volumio-black go-librespot[3197]: time="2026-08-30T22:43:42+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=info msg="zeroconf server listening on port 40091" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="obtained new client token: AAEypoKccetNaru8+FzC6XCp1HDy4BCkFh0j0e5ILd8RkCOfBvN4AL41fmFC80sXinijcs/yxnGFiZLkzqcwXjHDDYM60xDH+eYbWnKxSYIvkGZyx6WPty0Joc647UwVooOA5GIO0V+TGo82CQ1Ip4u7aBU5wFMrlHSHDTHOJr20Wl4oHay9rG9FNpQ8nbF1U2LmNFZukSeWQ4gCoUv0toei3V8CGcFikifb2cdUefIcnJjLMQrF7g==" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=debug msg="completed challenge" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:43 volumio-black go-librespot[3197]: time="2026-08-30T22:43:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:43 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:43 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:43 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:43 volumio-black volumio[1280]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:44 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::ClearQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::clearPlayQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:44 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3Y207UZ4wUCAzOWUrHoZ8a Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPlay Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::play index 0 Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::addQueueItems Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::addQueueItems Aug 30 22:43:44 volumio-black volumio[1280]: info: Preload queue cleared Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0AZTpwagcDe4nH0mPcnYhS Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4bNer4Cz6chRVexe3Z8hJv Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5fMInRpERS8jP2zPz6QyUI Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0FnVJjLJPRHgOWxucza08b Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1vtrfEsKAdbJfuHtddCAMe Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1OzFIbfhy8LdcbX9TJKj82 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2JRIqAEID7y4PUKZhJBPha Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3fqwjXwUGN6vbzIwvyFMhx Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3QKPcyXFQ5IudDOpdIZRj3 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4DI9D4LZjQZXBHGFeEAdmc Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1FqOGWc280BYbpRwLiPn4P Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2g0QvZOAlXf8nyu592CgsN Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5heR4UdIdf2ZC5C7vJGbE9 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0dMd4rilfd6gPbXaLpNYhu Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6J3HNhDBIwEX11bX8hzx5u Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6uSuDZyZminauHXtQNMFen Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1xQUAAiSpe8JxlQC77klij Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2oMfqntin1tncbli85BmnO Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4WIBvQtm11KmyTVExfHM1b Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0yOAmAgOpO9Jss3ZzQrf77 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5IjvNjAe9ZoZ322IBWvJBe Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:7o23a9OJut6JsgW3JEwFUn Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6YfMoXrlPZGoYzTJ8Svfgs Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4N0TP4Rmj6QQezWV88ARNJ Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4UX7OpafcTGey8CdA5zJkH Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:259d4MYHAadyTsY5pelMz8 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1RmJJPvyMSszOQHJ4s03A2 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0DIcHQUp4f9j4Gym2Kn8nm Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4LpFfd70HLdNIoVXZplfxH Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:10PzBYcWZEUbwZTC2844G3 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3U7eywWAUyKxVLEPeEzOqc Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6kIkjkxY2bp0PTSWpXl0mR Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:697lRzBkfPzmgFTj3PKMRK Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1AWz890n0HfGIucJa0RzQ1 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:1srQrPOCvEGRn5kScgDnTv Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5DflVzAvAHxlJCieAAGCnn Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2qRC8UXceLoMTP5E1QDhKK Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4lLbzbeP6JD15H4RnfSiA2 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6uXaAlQDX6TdqiQFCQDTYw Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4YAhuS6q8JebD31AWxsDvT Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:6nLvaCZFR1wEzW3sIKpsnr Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4faJXHXhki6LppYQjpA0hG Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0SEx1KQmfdesGopGQi4iv8 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5MxYNnD7kTJmJWSQvomDVO Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:2uKt3YMVIDuEDY2xH1jZFz Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3uJ7YB1zCQH0Y49sd5t1hq Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:58gPIRzKQteZDwUQKQAj0q Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4gi81cgaU0Xmek1IuOENM5 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:4GUaG3df9nbrj77GKEB76j Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:7gyDV6Y7GmHqQS4KnzI5Zk Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3PbcV8c3f6ZM8P7hyyxX2l Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:00Sx0hkJQi0sZgvcDxaCdu Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:5q4N14cDSyNaz5PzSu971y Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0ad1nLYLzBf0uSPxjCpZJi Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:3ETWwN2Fa8ihTN2LKpryV2 Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:0RNRRdxkgkMdUYWwOcRoIs Aug 30 22:43:44 volumio-black volumio[1280]: info: Adding Item to queue: spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:44 volumio-black volumio[1280]: info: Using cached record of: spotify:track:66jUX8GhpqIEaEWIimYuie Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::stop Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreCommandRouter::volumioPushQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::saveQueue Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::play index undefined Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::updateTrackBlock Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::getTrackBlock Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:44 volumio-black volumio[1280]: info: CoreStateMachine::startPlaybackTimer Aug 30 22:43:44 volumio-black volumio[1280]: info: CorePlayQueue::getTrack 0 Aug 30 22:43:44 volumio-black volumio[1280]: info: [1788122624270] ControllerSpotify::clearAddPlayTrack Aug 30 22:43:44 volumio-black volumio[1280]: info: Sending Spotify command with payload to local API: /player/play Aug 30 22:43:44 volumio-black volumio[1280]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:46 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 30 22:43:46 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:46 volumio-black systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:46 volumio-black go-librespot[3220]: go-librespot daemon starting... Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=info msg="running go-librespot 0.7.1" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="app state loaded" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="stored credentials not found" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=info msg="zeroconf server listening on port 46003" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="obtained new client token: AAHp0VG9B+Sf0sXhdA+nVHsqoS/NnI1S2dhkqQ77mjlmkYQvPkPobIwskjXOXuDNOBhcdqETBN//IbJLzwMkm0to4Es133zKATgLTj54hDbL2PBYxO6FeaRUdSXAR7V4AV8wE/A/2tcxxIFnnEz1fsK7Jd4VHcjJWrvVIidntLkaFHs2+U5AqGCKmdI+PfXPRvSisWTuB8MAqOkLyqgkIwFJKKge5u1mJm1uWhvulgRGD1REH9HjmTG1" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Aug 30 22:43:46 volumio-black volumio[1280]: info: Initializing connection to go-librespot Websocket Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="new websocket client" Aug 30 22:43:46 volumio-black volumio[1280]: info: Connection to go-librespot Websocket established Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="completed keyexchange" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=debug msg="completed challenge" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=info msg="authenticated AP" username="ba***85" Aug 30 22:43:46 volumio-black go-librespot[3221]: time="2026-08-30T22:43:46+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 30 22:43:46 volumio-black volumio[1280]: info: Connection to go-librespot Websocket closed Aug 30 22:43:46 volumio-black systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 30 22:43:46 volumio-black systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 30 22:43:49 volumio-black volumio[1280]: info: Getting Spotify volume Aug 30 22:43:49 volumio-black volumio[1280]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 22:43:49 volumio-black volumio[1280]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 30 22:43:49 volumio-black volumio[1280]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 30 22:43:49 volumio-black volumio[1280]: errno: -111, Aug 30 22:43:49 volumio-black volumio[1280]: code: 'ECONNREFUSED', Aug 30 22:43:49 volumio-black volumio[1280]: syscall: 'connect', Aug 30 22:43:49 volumio-black volumio[1280]: address: '127.0.0.1', Aug 30 22:43:49 volumio-black volumio[1280]: port: 9879, Aug 30 22:43:49 volumio-black volumio[1280]: response: undefined Aug 30 22:43:49 volumio-black volumio[1280]: } Aug 30 22:43:49 volumio-black volumio[1280]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 22:43:50 volumio-black systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Aug 30 22:43:50 volumio-black systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 30 22:43:50 volumio-black sudo[3245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 22:42' Aug 30 22:43:50 volumio-black sudo[3245]: 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"