Aug 27 15:35:00 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:00+02:00" level=trace msg="sent dealer ping"
Aug 27 15:35:00 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:00+02:00" level=trace msg="received dealer pong"
Aug 27 15:35:02 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:02 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:02 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:02 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:02 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:05 volumio-4b volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Aug 27 15:35:06 volumio-4b volumio[1299]: info: Received Get System Version
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 27 15:35:06 volumio-4b volumio[1299]: info: Received Get System Info
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:06 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:06 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:10 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:10+02:00" level=debug msg="fetched chunk 21/31, size: 524288" uri="spotify:track:1pUb1ZGMdSldjWGo7Nrg17"
Aug 27 15:35:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:14 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:14 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:23 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:23+02:00" level=debug msg="fetched chunk 22/31, size: 524288" uri="spotify:track:1pUb1ZGMdSldjWGo7Nrg17"
Aug 27 15:35:30 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:30+02:00" level=trace msg="received accesspoint ping"
Aug 27 15:35:30 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:30+02:00" level=trace msg="received accesspoint pong ack"
Aug 27 15:35:30 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:30+02:00" level=trace msg="sent dealer ping"
Aug 27 15:35:30 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:30+02:00" level=trace msg="received dealer pong"
Aug 27 15:35:32 volumio-4b volumio[1299]: verbose: New Socket.io Connection to 192.168.22.22:3000 from 192.168.22.10 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
Aug 27 15:35:32 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:32 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:32 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:32 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:32 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:33 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:33 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:33 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:33 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:33 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:37 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:37+02:00" level=debug msg="fetched chunk 23/31, size: 524288" uri="spotify:track:1pUb1ZGMdSldjWGo7Nrg17"
Aug 27 15:35:48 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:48 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:48 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:48 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:48 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:49 volumio-4b volumio[1299]: verbose: New Socket.io Connection to 192.168.22.22:3000 from 192.168.22.10 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
Aug 27 15:35:51 volumio-4b go-librespot[1639]: time="2026-08-27T15:35:51+02:00" level=debug msg="fetched chunk 24/31, size: 524288" uri="spotify:track:1pUb1ZGMdSldjWGo7Nrg17"
Aug 27 15:35:55 volumio-4b volumio[1299]: info: Received OAUTH Data
Aug 27 15:35:55 volumio-4b volumio[1299]: info: Executing Spotify Oauth Login
Aug 27 15:35:55 volumio-4b volumio[1299]: info: Saving Spotify Refresh Token
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: BQCnw2d3fcDJIhQZyogK9P-Fv1vcaERCmE0BhCTub_1llI_CZhvbbWgHJxzUJFjflYWVyWA8m5OiE_IoBu4XvLwgfNHfdN7l3gUyhoooObgXnfK2OlPtpPSPCWLwMq4PmK65UwF3Kod0WyAcxsfONxmuUHKFw4AN-d6tRR_3Zy4fvFyelCVsdb6qm3pgzyzUhZZClO8hYVQYqDyoCTmf0Jr5tBOc8OM0sStTLCSfQ1RLkHovY3WqbIQS-nkKtMAaXWSA1d-E2a7x53hEZOkp1M5tOe_t3IfIuJJ0oomAKLqV
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 27 15:35:56 volumio-4b volumio[1299]: info: New Spotify access token = BQCnw2d3fcDJIhQZyogK9P-Fv1vcaERCmE0BhCTub_1llI_CZhvbbWgHJxzUJFjflYWVyWA8m5OiE_IoBu4XvLwgfNHfdN7l3gUyhoooObgXnfK2OlPtpPSPCWLwMq4PmK65UwF3Kod0WyAcxsfONxmuUHKFw4AN-d6tRR_3Zy4fvFyelCVsdb6qm3pgzyzUhZZClO8hYVQYqDyoCTmf0Jr5tBOc8OM0sStTLCSfQ1RLkHovY3WqbIQS-nkKtMAaXWSA1d-E2a7x53hEZOkp1M5tOe_t3IfIuJJ0oomAKLqV
Aug 27 15:35:56 volumio-4b volumio[1299]: info: Spotify credentials grant success - running version from March 24, 2019
Aug 27 15:35:56 volumio-4b sudo[7894]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 27 15:35:56 volumio-4b sudo[7894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 15:35:56 volumio-4b sudo[7894]: pam_unix(sudo:session): session closed for user root
Aug 27 15:35:56 volumio-4b sudo[7896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 27 15:35:56 volumio-4b sudo[7896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 15:35:56 volumio-4b sudo[7896]: pam_unix(sudo:session): session closed for user root
Aug 27 15:35:56 volumio-4b volumio[1299]: verbose: New Socket.io Connection to 192.168.22.22 from 192.168.22.10 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/150.0.7871.181 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: User informations: {"account_id":"ZAesOZDeiM","country":"NL","display_name":"Maarten","email":"maarten.weemen@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/maarten.weemen"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/maarten.weemen","id":"maarten.weemen","images":[],"product":"premium","type":"user","uri":"spotify:user:maarten.weemen"}
Aug 27 15:35:56 volumio-4b volumio[1299]: info: Creating Spotify config file
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 27 15:35:56 volumio-4b volumio[1299]: info: Spotify config file written
Aug 27 15:35:56 volumio-4b sudo[7901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Aug 27 15:35:56 volumio-4b sudo[7901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 27 15:35:56 volumio-4b systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon...
Aug 27 15:35:56 volumio-4b systemd[1]: go-librespot-daemon.service: Killing process 1646 (go-librespot) with signal SIGKILL.
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 27 15:35:56 volumio-4b systemd[1]: go-librespot-daemon.service: Deactivated successfully.
Aug 27 15:35:56 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:35:56 volumio-4b systemd[1]: go-librespot-daemon.service: Consumed 6.285s CPU time.
Aug 27 15:35:56 volumio-4b volumio[1299]: info: Connection to go-librespot Websocket closed
Aug 27 15:35:56 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:35:56 volumio-4b sudo[7901]: pam_unix(sudo:session): session closed for user root
Aug 27 15:35:56 volumio-4b go-librespot[7903]: go-librespot daemon starting...
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=debug msg="app state loaded"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=debug msg="stored credentials not found"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: BQB6ic8ruqwCXhuW25TQEJe7s2rKGH-i53_oH6DenXgHz0AHRCkejQ9ssKzImOtKbQsUbII36PJvAF7BsG4EH_buD2lML5ZzxeZeXP-0Gb6y-SFiMQY4PV3zz_9ZI1X6gRD4sX_zis8Fp5G9gNl-gpN3Y7wWdPDS6hv9AgJGcxqfVF-dBeSwXcyjeq0RLUlIgXIF1vH1dRYHjdLihKFa3bDBjJgDENUvVVNvWEZ_L-cBddij18PJtVf5Zti3iQYEumAu84nBtdkrJmb_fyUrwhG6REAXTFS_vuS6rz0Hnbva
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------
Aug 27 15:35:56 volumio-4b volumio[1299]: info: New Spotify access token = BQB6ic8ruqwCXhuW25TQEJe7s2rKGH-i53_oH6DenXgHz0AHRCkejQ9ssKzImOtKbQsUbII36PJvAF7BsG4EH_buD2lML5ZzxeZeXP-0Gb6y-SFiMQY4PV3zz_9ZI1X6gRD4sX_zis8Fp5G9gNl-gpN3Y7wWdPDS6hv9AgJGcxqfVF-dBeSwXcyjeq0RLUlIgXIF1vH1dRYHjdLihKFa3bDBjJgDENUvVVNvWEZ_L-cBddij18PJtVf5Zti3iQYEumAu84nBtdkrJmb_fyUrwhG6REAXTFS_vuS6rz0Hnbva
Aug 27 15:35:56 volumio-4b volumio[1299]: info: Spotify credentials grant success - running version from March 24, 2019
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+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 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+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 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+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 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=info msg="zeroconf server listening on port 38425"
Aug 27 15:35:56 volumio-4b volumio[1299]: SPOTIFY: User informations: {"account_id":"ZAesOZDeiM","country":"NL","display_name":"Maarten","email":"maarten.weemen@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/maarten.weemen"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/maarten.weemen","id":"maarten.weemen","images":[],"product":"premium","type":"user","uri":"spotify:user:maarten.weemen"}
Aug 27 15:35:56 volumio-4b volumio[1299]: info: Spotify Successfully logged in
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 27 15:35:56 volumio-4b volumio[1299]: info: [1787837756598] CoreMusicLibrary::Adding element Spotify
Aug 27 15:35:56 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 27 15:35:56 volumio-4b volumio[1299]: Cannot find translation for source Radio Paradise
Aug 27 15:35:56 volumio-4b volumio[1299]: Cannot find translation for source Spotify
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=debug msg="obtained new client token: AAGAdqSuRJFCE2IArlq1tJKhpbIgYCTkWNxapxdKXq4uYq4GajZJBn1qpx8kUgMo9Mm+Q5UmYLT2mFWnsVdobDEMYRc+IfpyVX9GXZvLP0OaS0JULTz7V7Tpf/qBY/H5so7Ptv8gR18M73/uDdl4zmSp5mBX4bdZvoD7o4/8ypmv71ZNMkH6ey1K5INGahlqYn1EG0tWuYSq7sfCTOZJ41P/K5eQeH2y13Yh5S8rHCo0gCQ7+m9BHVxr"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=debug msg="completed keyexchange"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=debug msg="completed challenge"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=info msg="authenticated AP" username="ma**********en"
Aug 27 15:35:56 volumio-4b go-librespot[7904]: time="2026-08-27T15:35:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 27 15:35:56 volumio-4b systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 15:35:56 volumio-4b systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 27 15:35:58 volumio-4b volumio[1299]: info: Received Get System Info
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:58 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 27 15:35:58 volumio-4b volumio[1299]: info: Received Get System Info
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:35:58 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:35:58 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:35:59 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:35:59 volumio-4b volumio[1299]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:35:59 volumio-4b volumio[1299]: info: go-librespot daemon successfully initialized
Aug 27 15:35:59 volumio-4b systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Aug 27 15:35:59 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:00 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:00 volumio-4b go-librespot[7925]: go-librespot daemon starting...
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=debug msg="app state loaded"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=debug msg="stored credentials not found"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+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 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+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 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+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 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=info msg="zeroconf server listening on port 35387"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=debug msg="obtained new client token: AAFh1HL8gHR9ra4cKSYc5w3ow3dYr8cJL43aHnTlBS4yHTFw/lB8cS59tOV80BW6uJNySUzx7i7qyhyLqnnhVSHjlcZq3zJo5wjNAWRdMkXZB1619udNpLG7/doIweQdx83E4ldi3dW14TqRt+vA5C0ta0HR9QneNJowsJAAufG9hee8CdAjwkABOyLFr8VfT8sr6M72R7dGiT4ub+VXYboVr6jzzNqHpjePd21eHul4nFQM+ndsEA=="
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=debug msg="completed keyexchange"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=debug msg="completed challenge"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=info msg="authenticated AP" username="ma**********en"
Aug 27 15:36:00 volumio-4b go-librespot[7926]: time="2026-08-27T15:36:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 27 15:36:00 volumio-4b systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 15:36:00 volumio-4b systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 27 15:36:02 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:36:02 volumio-4b volumio[1299]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:36:02 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:36:02 volumio-4b volumio[1299]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:36:03 volumio-4b systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Aug 27 15:36:03 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:03 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:03 volumio-4b go-librespot[7933]: go-librespot daemon starting...
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=debug msg="app state loaded"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=debug msg="stored credentials not found"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+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 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+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 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+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 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=info msg="zeroconf server listening on port 41337"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=debug msg="obtained new client token: AAF5wsibC76bkE6dJVaE69TcxxbUK1cRY0PlXOCVrQmhPLMbrF+kr4q0xPS5LkQXgYcM7L9UTCVv67sSV4Y7Ghfq8ub135kF4Nve45x809BMYqp6Qf/NRLDhFF5FM/xsVle+gkod5NYaE2IqC48uqrrKVM1KOcNRqJN7GUMWxlAJvyd7osVr+GueeM9NbFiQJhvcsygz96X9dnRZyCO5ZV+jaThbZguEvbLreiOsPNvfsr3UfRU6C5uG"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+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 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=debug msg="completed keyexchange"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=debug msg="completed challenge"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=info msg="authenticated AP" username="ma**********en"
Aug 27 15:36:03 volumio-4b go-librespot[7934]: time="2026-08-27T15:36:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 27 15:36:03 volumio-4b systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 15:36:03 volumio-4b systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 27 15:36:05 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:36:05 volumio-4b volumio[1299]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:36:06 volumio-4b systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Aug 27 15:36:06 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:06 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:07 volumio-4b go-librespot[7941]: go-librespot daemon starting...
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=debug msg="app state loaded"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=debug msg="stored credentials not found"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+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 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+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 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+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 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=info msg="zeroconf server listening on port 37879"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=debug msg="obtained new client token: AAH3SkyObQenAucsFO1H/+cOB8txEb+QkF8fQtkgNSFF4vqvGhuYzO4RrgDsiWIHErernddHQ1xuwNl2beUDR8J5AYoj6aoqiCirF0x34HrgF5CWDLhbWnjiZ93rSJzOGPpNYcowzQC5vvpemI1yZSW0HLhLZPUZNl/Dk6BDSYujXZCLzo12yvuR2YFXOkcGnHLzTHWj+gNDmMGM4J8yXpmchYaEpbA8wsLTYAnG7690QArg2GLJGg=="
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=debug msg="completed keyexchange"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=debug msg="completed challenge"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=info msg="authenticated AP" username="ma**********en"
Aug 27 15:36:07 volumio-4b go-librespot[7942]: time="2026-08-27T15:36:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 27 15:36:07 volumio-4b systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 15:36:07 volumio-4b systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 27 15:36:08 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:36:08 volumio-4b volumio[1299]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:36:10 volumio-4b systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Aug 27 15:36:10 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:10 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:10 volumio-4b go-librespot[7965]: go-librespot daemon starting...
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=debug msg="app state loaded"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=debug msg="stored credentials not found"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+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 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+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 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+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 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=info msg="zeroconf server listening on port 41827"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=debug msg="obtained new client token: AAG3qq26REuBiR8SbuxOZfOwVBB30WbBd7hHMT1B0WSyAj+d513v3wMtF37EoGkr3dSsHfWVvAHBakMfMTsbRW6i9ivUSGHmQ6nTsenQImHExcp5YXwI0GowoPnwpdx/rOWfBMLxTDrL9ONFRlID9MhktxkvuOcBPJWxpbwLDFY6nVAe0gl8EXsdyfNZiM45gclj8QP9mjYNucPEhDx19w48fZTOMO5T1q8Rk5P9CGXB11amfG1IqD8d"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=debug msg="completed keyexchange"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=debug msg="completed challenge"
Aug 27 15:36:10 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:10+02:00" level=info msg="authenticated AP" username="ma**********en"
Aug 27 15:36:11 volumio-4b go-librespot[7966]: time="2026-08-27T15:36:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 27 15:36:11 volumio-4b systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 15:36:11 volumio-4b systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 27 15:36:11 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:36:11 volumio-4b volumio[1299]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:36:14 volumio-4b systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Aug 27 15:36:14 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:14 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:14 volumio-4b go-librespot[7976]: go-librespot daemon starting...
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="app state loaded"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="stored credentials not found"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Aug 27 15:36:14 volumio-4b volumio[1299]: info: Received Get System Version
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 27 15:36:14 volumio-4b volumio[1299]: info: Received Get System Info
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 27 15:36:14 volumio-4b volumio[1299]: info: Discovery: Getting this device information
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::volumioGetState
Aug 27 15:36:14 volumio-4b volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+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 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+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 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+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 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=info msg="zeroconf server listening on port 38509"
Aug 27 15:36:14 volumio-4b volumio[1299]: info: Initializing connection to go-librespot Websocket
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="new websocket client"
Aug 27 15:36:14 volumio-4b volumio[1299]: info: Connection to go-librespot Websocket established
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="obtained new client token: AAFsv8aU3daa4kPl8vAQAuR0tuhR5JURgKLk/dpICcHW+ntJqqUeLzLap100FCe1JtzUP5Rl1b+1rswjT0rIQQ29dcjLl180q+S8o4x3tHRcBQP+P6e8A/X7WOPuezFeOVogD1RlTRa5XUmOc5f1xVQK26iKML/+dbDRH0/5w1/JizZQ7su2jjU5+NSkIDCb6bbPxVo5Mv9k6ExUADsKO3mFHbQ4u9SSgomfOSh3/Fq6KKoJN6Hl02Pt"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="completed keyexchange"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=debug msg="completed challenge"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=info msg="authenticated AP" username="ma**********en"
Aug 27 15:36:14 volumio-4b go-librespot[7977]: time="2026-08-27T15:36:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS"
Aug 27 15:36:14 volumio-4b systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Aug 27 15:36:14 volumio-4b volumio[1299]: info: Connection to go-librespot Websocket closed
Aug 27 15:36:14 volumio-4b systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Aug 27 15:36:17 volumio-4b volumio[1299]: info: Getting Spotify volume
Aug 27 15:36:17 volumio-4b volumio[1299]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 27 15:36:17 volumio-4b volumio[1299]: Error: connect ECONNREFUSED 127.0.0.1:9879
Aug 27 15:36:17 volumio-4b volumio[1299]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Aug 27 15:36:17 volumio-4b volumio[1299]: errno: -111,
Aug 27 15:36:17 volumio-4b volumio[1299]: code: 'ECONNREFUSED',
Aug 27 15:36:17 volumio-4b volumio[1299]: syscall: 'connect',
Aug 27 15:36:17 volumio-4b volumio[1299]: address: '127.0.0.1',
Aug 27 15:36:17 volumio-4b volumio[1299]: port: 9879,
Aug 27 15:36:17 volumio-4b volumio[1299]: response: undefined
Aug 27 15:36:17 volumio-4b volumio[1299]: }
Aug 27 15:36:17 volumio-4b volumio[1299]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 27 15:36:17 volumio-4b systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Aug 27 15:36:17 volumio-4b systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:17 volumio-4b go-librespot[8010]: go-librespot daemon starting...
Aug 27 15:36:17 volumio-4b systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Aug 27 15:36:17 volumio-4b go-librespot[8011]: time="2026-08-27T15:36:17+02:00" level=info msg="running go-librespot 0.4.0"
Aug 27 15:36:17 volumio-4b go-librespot[8011]: time="2026-08-27T15:36:17+02:00" level=debug msg="app state loaded"
Aug 27 15:36:17 volumio-4b go-librespot[8011]: time="2026-08-27T15:36:17+02:00" level=debug msg="stored credentials not found"
Aug 27 15:36:17 volumio-4b go-librespot[8011]: time="2026-08-27T15:36:17+02:00" level=info msg="api server listening on 127.0.0.1:9879"
Aug 27 15:36:17 volumio-4b sudo[8019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 15:35'
Aug 27 15:36:17 volumio-4b sudo[8019]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025"
VOLUMIO_VERSION="4.073"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"