Oct 17 07:24:01 bcik go-librespot[14746]: time="2026-10-17T07:24:01+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Oct 17 07:24:01 bcik go-librespot[14746]: time="2026-10-17T07:24:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 17 07:24:01 bcik go-librespot[14746]: time="2026-10-17T07:24:01+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:01 bcik go-librespot[14746]: time="2026-10-17T07:24:01+02:00" level=debug msg="completed challenge" Oct 17 07:24:01 bcik go-librespot[14746]: time="2026-10-17T07:24:01+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:01 bcik go-librespot[14746]: time="2026-10-17T07:24:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:01 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:01 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:01 bcik volumio[14084]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Oct 17 07:24:01 bcik volumio[14084]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 07:24:01 bcik volumio[14084]: Error: socket hang up Oct 17 07:24:01 bcik volumio[14084]: at connResetException (node:internal/errors:720:14) Oct 17 07:24:01 bcik volumio[14084]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 17 07:24:01 bcik volumio[14084]: at Socket.emit (node:events:526:35) Oct 17 07:24:01 bcik volumio[14084]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 17 07:24:01 bcik volumio[14084]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 17 07:24:01 bcik volumio[14084]: code: 'ECONNRESET', Oct 17 07:24:01 bcik volumio[14084]: response: undefined Oct 17 07:24:01 bcik volumio[14084]: } Oct 17 07:24:01 bcik volumio[14084]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 07:24:01 bcik sudo[15676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 07:23' Oct 17 07:24:01 bcik sudo[15676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:01 bcik sudo[15676]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:02 bcik volumio-remote-updater[9031]: [2026-10-17 07:24:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 17 07:24:02 bcik volumio-remote-updater[9031]: [2026-10-17 07:24:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 17 07:24:02 bcik systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:02 bcik volumio5-onboarding[14428]: time=2026-10-17T07:24:02.032+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 17 07:24:02 bcik volumio5-onboarding[14428]: time=2026-10-17T07:24:02.033+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 17 07:24:02 bcik systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 17 07:24:02 bcik systemd[1]: volumio.service: Consumed 9.017s CPU time. Oct 17 07:24:02 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 07:24:02 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 07:24:02 bcik systemd[1]: volumio.service: Scheduled restart job, restart counter is at 28415. Oct 17 07:24:02 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 17 07:24:02 bcik systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 17 07:24:02 bcik systemd[1]: volumio.service: Consumed 9.017s CPU time. Oct 17 07:24:02 bcik systemd[1]: Started volumio.service - Volumio Backend Module. Oct 17 07:24:02 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 17 07:24:02 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:02 bcik volumio[15716]: info: ----- Volumio3 ---- Oct 17 07:24:02 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:02 bcik volumio[15716]: info: ----- System startup ---- Oct 17 07:24:02 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:02 bcik volumio[15716]: info: MYVOLUMIO Environment detected Oct 17 07:24:02 bcik volumio[15716]: info: Plugin folders cleanup Oct 17 07:24:02 bcik volumio[15716]: info: Scanning into folder /volumio/app/plugins/ Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category audio_interface Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category miscellanea Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category music_service Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category plugins.json Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category system_controller Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category user_interface Oct 17 07:24:02 bcik volumio[15716]: info: Scanning into folder /data/plugins/ Oct 17 07:24:02 bcik volumio[15716]: info: Scanning category music_service Oct 17 07:24:02 bcik volumio[15716]: info: Plugin folders cleanup completed Oct 17 07:24:02 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:02 bcik volumio[15716]: info: ----- Core plugins startup ---- Oct 17 07:24:02 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:02 bcik volumio[15716]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 07:24:02 bcik volumio[15716]: info: Adding plugin upnp to MyMusic Plugins Oct 17 07:24:02 bcik volumio[15716]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 07:24:02 bcik volumio[15716]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 07:24:02 bcik volumio[15716]: info: Loading plugins from folder /data/plugins/ Oct 17 07:24:02 bcik volumio[15716]: info: Loading plugin "system"... Oct 17 07:24:02 bcik volumio[15716]: info: Loading plugin "appearance"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "network"... Oct 17 07:24:03 bcik volumio[15716]: info: Refreshing Cached IP Addresses Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "services"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "volumio5onboarding"... Oct 17 07:24:03 bcik sudo[15748]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 07:24:03 bcik sudo[15748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:03 bcik sudo[15746]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 07:24:03 bcik sudo[15746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:03 bcik sudo[15746]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "alsa_controller"... Oct 17 07:24:03 bcik sudo[15748]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "wizard"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "networkfs"... Oct 17 07:24:03 bcik volumio[15716]: info: Starting Udev Watcher for removable devices Oct 17 07:24:03 bcik volumio[15716]: info: Ignoring mount for partition: boot Oct 17 07:24:03 bcik volumio[15716]: info: Ignoring mount for partition: volumio Oct 17 07:24:03 bcik volumio[15716]: info: Ignoring mount for partition: volumio_data Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "volumio_command_line_client"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "upnp"... Oct 17 07:24:03 bcik volumio[15716]: info: [1792214643098] Starting Upmpd Daemon Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "my_music"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "mpd"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "upnp_browser"... Oct 17 07:24:03 bcik volumio[15716]: info: Starting UPNP Browser Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "alarm-clock"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "airplay_emulation"... Oct 17 07:24:03 bcik volumio[15716]: info: Starting Shairport Sync Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "last_100"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "webradio"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "i2s_dacs"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "volumiodiscovery"... Oct 17 07:24:03 bcik volumio[15716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 07:24:03 bcik volumio[15716]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 07:24:03 bcik volumio[15716]: *** WARNING *** For more information see Oct 17 07:24:03 bcik volumio[15716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 07:24:03 bcik volumio[15716]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 07:24:03 bcik volumio[15716]: *** WARNING *** For more information see Oct 17 07:24:03 bcik node[15716]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 07:24:03 bcik node[15716]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 07:24:03 bcik node[15716]: *** WARNING *** For more information see Oct 17 07:24:03 bcik node[15716]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 07:24:03 bcik node[15716]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 07:24:03 bcik node[15716]: *** WARNING *** For more information see Oct 17 07:24:03 bcik volumio[15716]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 07:24:03 bcik volumio[15716]: info: Discovery: Started advertising with name: Bącik Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "spop"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "outputs"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "albumart"... Oct 17 07:24:03 bcik volumio[15716]: info: Plugin example_plugin is not enabled Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "inputs"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "updater_comm"... Oct 17 07:24:03 bcik volumio[15716]: info: Plugin mpdemulation is not enabled Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "rest_api"... Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "websocket"... Oct 17 07:24:03 bcik volumio[15716]: info: Starting Socket.io Server version 1.7.4 Oct 17 07:24:03 bcik volumio[15716]: info: Loading plugin "radio_browser"... Oct 17 07:24:03 bcik volumio[15778]: Forking 3 albumart workers Oct 17 07:24:03 bcik volumio[15716]: info: Loading i18n strings for locale pl Oct 17 07:24:03 bcik volumio[15716]: Updating browse sources language Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::initPlayerControls Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: Express server listening on port 3000 Oct 17 07:24:03 bcik volumio[15716]: [Metrics] WebUI: 1s 267.68ms Oct 17 07:24:03 bcik volumio[15716]: info: CoreStateMachine::resetVolumioState Oct 17 07:24:03 bcik volumio[15716]: info: CoreStateMachine::getcurrentVolume Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 07:24:03 bcik volumio[15716]: info: Volumio Network Manager: Network status updated: 1 Oct 17 07:24:03 bcik volumio[15716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 07:24:03 bcik volumio[15716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:03 bcik volumio[15716]: info: Reloading queue from file Oct 17 07:24:03 bcik volumio[15716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Oct 17 07:24:03 bcik volumio[15716]: info: VolumeController:: Volume=41 Mute =false Oct 17 07:24:03 bcik volumio[15716]: info: CoreStateMachine::pushState Oct 17 07:24:03 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::volumioPushState Oct 17 07:24:03 bcik volumio[15716]: info: CoreStateMachine::updateTrackBlock Oct 17 07:24:03 bcik volumio[15716]: info: CorePlayQueue::getTrackBlock Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 07:24:03 bcik volumio[15716]: info: Setting Device type: Raspberry PI Oct 17 07:24:03 bcik volumio[15716]: info: CoreStateMachine::setRepeat false single undefined Oct 17 07:24:03 bcik volumio[15716]: info: CoreStateMachine::pushState Oct 17 07:24:03 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:24:03 bcik volumio[15716]: info: CoreCommandRouter::volumioPushState Oct 17 07:24:04 bcik volumio[15716]: info: CoreStateMachine::setRandom false Oct 17 07:24:04 bcik volumio[15716]: info: CoreStateMachine::pushState Oct 17 07:24:04 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioPushState Oct 17 07:24:04 bcik volumio[15716]: info: Completed loading Core Plugins Oct 17 07:24:04 bcik volumio[15716]: info: Preparing to generate the ALSA configuration file Oct 17 07:24:04 bcik volumio[15716]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 17 07:24:04 bcik volumio[15716]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04171 Oct 17 07:24:04 bcik volumio[15716]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 17 07:24:04 bcik volumio[15716]: info: Asound.conf file unchanged, so no further update is needed Oct 17 07:24:04 bcik volumio[15716]: info: Output device has changed, restarting MPD Oct 17 07:24:04 bcik volumio[15716]: info: Output device has changed, restarting Shairport Sync Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:04 bcik sudo[15833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 07:24:04 bcik sudo[15833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik sudo[15833]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik volumio[15716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 07:24:04 bcik sudo[15835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 07:24:04 bcik volumio[15716]: info: ___________ START PLUGINS ___________ Oct 17 07:24:04 bcik sudo[15835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik volumio[15716]: info: ControllerMpd::onStart: Initializing MPD Oct 17 07:24:04 bcik volumio[15716]: info: Creating MPD Configuration file Oct 17 07:24:04 bcik sudo[15843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 17 07:24:04 bcik sudo[15843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 07:24:04 bcik sudo[15845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 07:24:04 bcik sudo[15845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 07:24:04 bcik volumio[15716]: info: [1792214644092] CoreMusicLibrary::Adding element Serwery Mediów Oct 17 07:24:04 bcik sudo[15845]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 07:24:04 bcik volumio[15788]: Starting albumart workers Oct 17 07:24:04 bcik volumio[15716]: info: UPNP Browser: Client initialized successfully Oct 17 07:24:04 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 17 07:24:04 bcik volumio[15789]: Starting albumart workers Oct 17 07:24:04 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 07:24:04 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 17 07:24:04 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 07:24:04 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 07:24:04 bcik sudo[15848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 07:24:04 bcik sudo[15848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:04 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 07:24:04 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 07:24:04 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 17 07:24:04 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 07:24:04 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 17 07:24:04 bcik volumio[15716]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 07:24:04 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 07:24:04 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 07:24:04 bcik volumio[15716]: info: [1792214644146] CoreMusicLibrary::Adding element Last_100 Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 07:24:04 bcik volumio[15716]: info: [1792214644147] CoreMusicLibrary::Adding element Webradio Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 07:24:04 bcik sudo[15843]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 07:24:04 bcik volumio[15716]: info: Initializing BBC Radios Oct 17 07:24:04 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 07:24:04 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 07:24:04 bcik volumio[15790]: Starting albumart workers Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: Creating Spotify config file Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik sudo[15864]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 07:24:04 bcik sudo[15864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 07:24:04 bcik sudo[15864]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 07:24:04 bcik volumio[15716]: info: [1792214644292] CoreMusicLibrary::Adding element Radio Browser Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 07:24:04 bcik volumio[15716]: Cannot find translation for source Radio Browser Oct 17 07:24:04 bcik volumio[15716]: info: Volumio Calling Home Oct 17 07:24:04 bcik volumio[15716]: info: MPD Permissions set Oct 17 07:24:04 bcik volumio[15716]: info: MPD Permissions set Oct 17 07:24:04 bcik volumio[15716]: info: VolumeController:: Volume=41 Mute =false Oct 17 07:24:04 bcik volumio[15716]: info: CoreStateMachine::pushState Oct 17 07:24:04 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioPushState Oct 17 07:24:04 bcik volumio[15716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 17 07:24:04 bcik volumio[15716]: info: Volumio called home Oct 17 07:24:04 bcik volumio[15716]: info: Spotify config file written Oct 17 07:24:04 bcik sudo[15900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 17 07:24:04 bcik sudo[15900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:04 bcik volumio[15716]: info: No need to fix Spotify hosts Oct 17 07:24:04 bcik go-librespot[15902]: go-librespot daemon starting... Oct 17 07:24:04 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:04 bcik sudo[15900]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=debug msg="app state loaded" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:04 bcik volumio[15716]: info: Starting Shairport Sync Oct 17 07:24:04 bcik volumio[15716]: info: Starting Shairport Sync Oct 17 07:24:04 bcik volumio[15716]: info: Starting Shairport Sync Oct 17 07:24:04 bcik sudo[15922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 07:24:04 bcik sudo[15922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik sudo[15927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 07:24:04 bcik sudo[15927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik sudo[15925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 07:24:04 bcik sudo[15925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:04 bcik volumio[15716]: info: Discovery: adding f1ed5fb6-e21d-47c8-819b-29842600355e Oct 17 07:24:04 bcik volumio[15716]: info: Discovery: Found device Bącik Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioGetState Oct 17 07:24:04 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:04 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 07:24:04 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 07:24:04 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 07:24:04 bcik systemd[1]: shairport-sync.service: Consumed 2.033s CPU time. Oct 17 07:24:04 bcik volumio[15716]: info: Discovery: this is already registered, f1ed5fb6-e21d-47c8-819b-29842600355e Oct 17 07:24:04 bcik volumio[15716]: info: Discovery: Found device Bącik Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioGetState Oct 17 07:24:04 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:04 bcik volumio[15716]: info: An error occurred while refreshing Spotify Token Error: Bad Request Oct 17 07:24:04 bcik volumio[15716]: info: CoreCommandRouter::volumioGetState Oct 17 07:24:04 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:04 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 07:24:04 bcik sudo[15927]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik sudo[15925]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik sudo[15922]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik volumio[15716]: info: Shairport-Sync Started Oct 17 07:24:04 bcik volumio[15716]: Error adding Membership: Error: addMembership EINVAL Oct 17 07:24:04 bcik volumio[15716]: info: Shairport-Sync Started Oct 17 07:24:04 bcik volumio[15716]: info: Shairport-Sync Started Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+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]" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+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]" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+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]" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=info msg="zeroconf server listening on port 41445" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=debug msg="obtained new client token: AAGjWsa2txumclXYQ/eIGEHsmBMLewkuFRldG22+D+CA5qe7bR941VIIbm04O++gmf/jAvQ4GDNYUupY6poMAsRgO6YS/2bBRhFRxIhy1I0USK4PWhvwIGInzmvfw1lwjP6zn/9vwOyumvvA+O6rm/gMVxyI+QsMHNSUj4ttxGQl+22ZZFEIuW7C3l4uJGUfI3B6MvnvORpIbe4IyQ3C3vW6IXMr+2d0SrfBCMXoZ0F6JpmFWAfwuQQ=" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=debug msg="completed challenge" Oct 17 07:24:04 bcik mpd[15877]: 2026-10-17T07:24:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:04 bcik systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 07:24:04 bcik sudo[15848]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik sudo[15835]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:04 bcik volumio[15716]: info: Completed starting Core Plugins Oct 17 07:24:04 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:04 bcik volumio[15716]: info: ----- MyVolumio plugins startup ---- Oct 17 07:24:04 bcik volumio[15716]: info: ------------------------------------------- Oct 17 07:24:04 bcik volumio[15716]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 07:24:04 bcik volumio[15716]: error: MPD error: The expression evaluated to a falsy value: Oct 17 07:24:04 bcik volumio[15716]: assert.ok(self.idling) Oct 17 07:24:04 bcik volumio[15716]: error: The expression evaluated to a falsy value: Oct 17 07:24:04 bcik volumio[15716]: assert.ok(self.idling) Oct 17 07:24:04 bcik volumio[15716]: info: MPD running with PID15877 Oct 17 07:24:04 bcik volumio[15716]: ,establishing connection Oct 17 07:24:04 bcik volumio[15716]: error: updateQueue error: null Oct 17 07:24:04 bcik volumio[15716]: error: updateQueue error: null Oct 17 07:24:04 bcik go-librespot[15913]: time="2026-10-17T07:24:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:04 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:04 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:07 bcik volumio-remote-updater[9031]: [2026-10-17 07:24:07] [connect] Successful connection Oct 17 07:24:07 bcik volumio-remote-updater[9031]: [2026-10-17 07:24:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792214647 101 Oct 17 07:24:07 bcik volumio[15716]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 17 07:24:07 bcik volumio[15716]: info: go-librespot daemon successfully initialized Oct 17 07:24:08 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4454. Oct 17 07:24:08 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:08 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:08 bcik go-librespot[15952]: go-librespot daemon starting... Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=debug msg="app state loaded" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+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]" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+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]" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+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]" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=info msg="zeroconf server listening on port 36425" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=debug msg="obtained new client token: AAHBJeRN/Mi6c9IetPtcMVdKntd2TcBqfvtBtjGVHAPolUjLpLlBaP7Nr8LJRKpQsdHb5dUn+TWOqbGrfmezUaig5mea9Xjjeq3m8Qn13CCog5Ivj9SbR5/8ttJ5fFCvi7C/am5XPn7JUiFPr/ZLIohET0M+Trg2KjM0m6/oSrbwvAHsDUEUHY/sNq/l3KMwPmjvjGz/yzdqnZpXG8l9x8bTmOWE1O1JVhAXbHcP5eXvaUj+kQzI" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=debug msg="completed challenge" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:08 bcik go-librespot[15953]: time="2026-10-17T07:24:08+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:08 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:08 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:09 bcik volumio[15716]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 17 07:24:10 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:10 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:11 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4455. Oct 17 07:24:11 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:11 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:11 bcik go-librespot[15962]: go-librespot daemon starting... Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=debug msg="app state loaded" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+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]" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+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]" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+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]" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=info msg="zeroconf server listening on port 37093" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=debug msg="obtained new client token: AAFrDz8RPSolurSn1sKNylSJTL4LaVbpyOnSHFG6b6vkf7Ebl7iotkcgcjXHzBlttBL5dF6eYQ6n2cgCaYpa9xTBqsI6PqpgPTjZGtIf7eU4lzqwapkg42qeeyGcbJdbEG7Nvw34RTaCmiHwX57A4bkiBLTeh045b20zwMyFTvMfLr0TCGg9vzMRxosZhO9ao8WyQG+2zBDsgatrxvIzxzMAdDQWwjJ6uEeMsyoVKCg2UfdKgY8XTi4=" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=debug msg="completed challenge" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:11 bcik go-librespot[15963]: time="2026-10-17T07:24:11+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:11 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:11 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:13 bcik sudo[15987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 07:24:13 bcik sudo[15987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:13 bcik sudo[15987]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:13 bcik sudo[15989]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 07:24:13 bcik sudo[15989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:13 bcik sudo[15989]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:13 bcik sudo[15992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 07:24:13 bcik sudo[15992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:13 bcik sudo[15992]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:13 bcik volumio[15716]: info: Upmpdcli Daemon Started Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin bluetooth to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin multiroom to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin metavolumio to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin cd_controller to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 17 07:24:13 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:13 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:13 bcik volumio[15716]: info: Starting MyVolumio Remote Streaming Endpoints Oct 17 07:24:13 bcik volumio[15716]: info: MyVolumio login type: Token Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 17 07:24:13 bcik volumio[15716]: info: Streaming services startup Oct 17 07:24:13 bcik volumio[15716]: info: Starting Streaming Daemon Oct 17 07:24:13 bcik sudo[15997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 07:24:13 bcik volumio[15716]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 17 07:24:13 bcik sudo[15997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:13 bcik sudo[15997]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:13 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:13 bcik volumio[15716]: error: Cannot start Volumio Streaming Daemon Oct 17 07:24:13 bcik volumio[15716]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 07:24:13 bcik volumio[15716]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 07:24:13 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:14 bcik volumio[15716]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 17 07:24:14 bcik volumio[15716]: info: MyVolumio token set successfully Oct 17 07:24:14 bcik volumio[15716]: info: MYVOLUMIO: Adding device Oct 17 07:24:14 bcik volumio[15716]: info: MYVOLUMIO: Evaluating Server Oct 17 07:24:14 bcik volumio[15716]: info: MyVolumio status changed Oct 17 07:24:14 bcik volumio[15716]: info: Streaming services startup Oct 17 07:24:14 bcik volumio[15716]: info: Starting Streaming Daemon Oct 17 07:24:14 bcik volumio[15716]: info: Removing browser output: myVolumio user plan is not superstar Oct 17 07:24:14 bcik volumio[15716]: info: Removing audio output: Oct 17 07:24:14 bcik volumio[15716]: info: Stoppping Tunnel 1 Oct 17 07:24:14 bcik sudo[16024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 07:24:14 bcik sudo[16024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:14 bcik sudo[16026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 17 07:24:14 bcik sudo[16026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik sudo[16024]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:14 bcik volumio[15716]: error: Cannot start Volumio Streaming Daemon Oct 17 07:24:14 bcik volumio[15716]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 07:24:14 bcik volumio[15716]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 17 07:24:14 bcik sudo[16026]: pam_unix(sudo:session): session closed for user root Oct 17 07:24:14 bcik volumio[15716]: info: Remote SSH Stopped Oct 17 07:24:14 bcik volumio[15716]: info: Setting Geolocation for MyVolumio to eu6 Oct 17 07:24:14 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:14 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:14 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:15 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4456. Oct 17 07:24:15 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:15 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:15 bcik go-librespot[16029]: go-librespot daemon starting... Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=debug msg="app state loaded" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+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]" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+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]" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+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]" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=info msg="zeroconf server listening on port 42365" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=debug msg="obtained new client token: AAE42iwJA6wg19Sbi/U0MCl4JKSgGtzsV9w4E6ErZpZ7ZQpBUEQsKXCCjHJ4QmsbAq7ScVTbVYB3Y+W6oUBK27TpAdzlumc7iLLaS+CVhgh12CXowFULlqTv978RX99Itu3I/3EggAfHDNPW4SIjBjXKrqvNGPiEZoBYJ+cypLgrYYE9zz6Hn1apTE9gpo9ZltzS6TqanpwhZX0p1TlZ1bMuu0IgkkErmZH0ajJAtjYhsRA+PJo6" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:15 bcik volumio[15716]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=debug msg="completed challenge" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:15 bcik go-librespot[16030]: time="2026-10-17T07:24:15+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:15 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:15 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:15 bcik volumio[15716]: info: Updating MyVolumio device info Oct 17 07:24:15 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:15 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:15 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:16 bcik volumio[15716]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 17 07:24:16 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:16 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:19 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4457. Oct 17 07:24:19 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:19 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:19 bcik go-librespot[16040]: go-librespot daemon starting... Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=debug msg="app state loaded" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+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]" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+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]" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+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]" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=info msg="zeroconf server listening on port 45769" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=debug msg="obtained new client token: AAG50WSJU9n9bANzR6zTfsvJPV6NJGRwt590GXkw1sSRPK5535soks9qbQ/6Ke91DcmjKWUD/L1nX0AjAnzHCSPoUTixbnbcvQTSy6ublbFQrod2QglhVcR31rxPxfki08lcaOlul31TAPAODqUol6kxqYP2+o/CdIZdTHZgZvAF4ZllADqCzOMUnwL54Rdxk6klK0OvcHy3b6SFAPG5Wb8wFxcbpevoJW7OBPHrF0wRM4Rsnob1" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=debug msg="completed challenge" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:19 bcik go-librespot[16041]: time="2026-10-17T07:24:19+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:19 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:19 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:19 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:19 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:19 bcik volumio[15716]: info: MYVOLUMIO: Adding device Oct 17 07:24:19 bcik volumio[15716]: info: MYVOLUMIO: Evaluating Server Oct 17 07:24:20 bcik volumio[15716]: info: Setting Geolocation for MyVolumio to eu6 Oct 17 07:24:20 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:20 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:20 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:20 bcik volumio[15716]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 17 07:24:21 bcik volumio[15716]: info: Updating MyVolumio device info Oct 17 07:24:21 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:21 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:21 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:21 bcik volumio[15716]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 17 07:24:22 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4458. Oct 17 07:24:22 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:22 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:22 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:22 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:22 bcik go-librespot[16068]: go-librespot daemon starting... Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=debug msg="app state loaded" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+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]" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+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]" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+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]" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=info msg="zeroconf server listening on port 46541" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=debug msg="obtained new client token: AAF9aSNXzPdYcGfEnKS0Gu/3xhRJ2a4f/bJPk9Ore/u27z05wgcS+QFVorD2f6zP7mI8zH7XzkX0H3S4xXYCfm0a5Zt8CMlgfe3b0hfAhzH1L45iaeB5EhrRaX0r17jIAz3Wz6yreom5MMsMuXQEiwkJd7ROMW7WQnDBviCudNEKxEe71CdhEjYCrky60uT4NB5K01CScaRX7wWVk0ay6/IOuDjRDhcua+OADrqNSeviANu5VK8TS4w=" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=debug msg="completed challenge" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:22 bcik go-librespot[16069]: time="2026-10-17T07:24:22+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:22 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:22 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:25 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:25 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:26 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4459. Oct 17 07:24:26 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:26 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:26 bcik go-librespot[16092]: go-librespot daemon starting... Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=debug msg="app state loaded" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+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]" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+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]" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+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]" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=info msg="zeroconf server listening on port 45239" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=debug msg="obtained new client token: AAFpwLi0oWdXR2l4ZAo5E1ueo+04F2lL5XTKVt1OXjiq7uGjWvftIP2URXitezAA6tjArXr4RRPLgP5K9j5TV0W+LlZgZPkYt86ol+JFeKCt2eHnlhNwu8aNaZWyg37TaJIfPXNVVvk+UV8XFlXnhGVe4RVrgazhm7GbDXhhJ8iTWMj7+CovDW3tFHT560UxcE00rTUPpWA6Gf/CBNMnxUYFaWpkM26Mn0IfjiPcZRPZhir1VTzP" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=debug msg="completed challenge" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:26 bcik go-librespot[16093]: time="2026-10-17T07:24:26+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:26 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:26 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:28 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 07:24:28 bcik volumio[15716]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 17 07:24:28 bcik volumio[15716]: info: CoreCommandRouter::volumioGetState Oct 17 07:24:28 bcik volumio[15716]: info: CorePlayQueue::getTrack 0 Oct 17 07:24:28 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:28 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:29 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4460. Oct 17 07:24:29 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:29 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:29 bcik go-librespot[16102]: go-librespot daemon starting... Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=debug msg="app state loaded" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+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]" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+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]" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+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]" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=info msg="zeroconf server listening on port 41777" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=debug msg="obtained new client token: AAHYqeoKbwQZBuipCterZVb+WUrY/OFvgD2NSli+ugXyZACUHlGd2pFlPQaLco2+aGZzoztJPQK0XFD3w+58pEWjPXITqD6z/LbRX4t2CvxrB3PSScLhqXX3mb4bEpnSVH9UqWq88Az8nhDwTTB0EsEcXh+eMu3hXtnONlMuqnIskHCAktUOZPQS6YlUblASlt0g1MUo3+oNz3qQRMA7NxH5hpH98u4jvuwyRp+1+H+RfLhdp0h+P5c=" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=debug msg="completed challenge" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:29 bcik go-librespot[16103]: time="2026-10-17T07:24:29+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:29 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:29 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:31 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:31 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:33 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4461. Oct 17 07:24:33 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:33 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:33 bcik go-librespot[16112]: go-librespot daemon starting... Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=debug msg="app state loaded" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+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]" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+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]" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+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]" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=info msg="zeroconf server listening on port 38403" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=debug msg="obtained new client token: AAHBAxdb8NPaFscfbh7tzyN24sSreYqN2JRvFp3R0whZD6iozjdEOQWBpQjYyAqcvS73PVEpPp32ul4eJu6AR1mqiFCV/2p7IEbb6gQD8AvbVIzuPLJY7zusrDaPCQLLMYlf2wiWZ+YeYJIqF907UsoMBnFMw23HnpfLs+e2C7F2lNsmi/g36Hb01YQ8HaHljHjOReVKJBUUciafBf0/V8veCmaIUBU0Xu/SSzCZoC6NhLMqjjtU" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=debug msg="completed challenge" Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 07:24:33 bcik go-librespot[16113]: time="2026-10-17T07:24:33+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:33 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:33 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:33 bcik volumio[15716]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 17 07:24:33 bcik volumio[15716]: info: Completed starting MyVolumio Plugin Oct 17 07:24:33 bcik volumio[15716]: [Metrics] CommandRouter: 30s 830.09ms Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::volumiosetStartupVolume Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::Close All Modals sent Oct 17 07:24:33 bcik volumio[15716]: info: CoreCommandRouter::Close All Modals sent Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 07:24:34 bcik volumio-remote-updater[9031]: Test mode disabled Oct 17 07:24:34 bcik volumio-remote-updater[9031]: Alpha mode disabled Oct 17 07:24:34 bcik volumio-remote-updater[9031]: Alpha legacy test mode disabled Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 17 07:24:34 bcik volumio[15716]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 07:24:34 bcik volumio[15716]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 17 07:24:34 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:34 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:36 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4462. Oct 17 07:24:36 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:36 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:36 bcik go-librespot[16142]: go-librespot daemon starting... Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=debug msg="app state loaded" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+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]" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+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]" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+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]" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=info msg="zeroconf server listening on port 35969" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=debug msg="obtained new client token: AAFPIpVzpo9unM7+dIJsgYnJP4ZWUeoMhEG+yERHq7cwlxTkxNIfrYo1SFs5x8OTi5mZA7kpba7cES3qeJlmSNxJs9qI5VPV7W3w4Y0EkXi2WUmiDEl8PDHPmQMuwK3iIBOp6/TaQb+L7wU/5+6qzMdwCrykj/gu/ya/c0M7S/uQWGjD7j7jM98qOTmmX+ul8RFFok5cJ7ic4rk6jp6cgRgHSAIRr8u23lXCTtTRuZzGlNufSGUcs7o=" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+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" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=debug msg="completed challenge" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:36 bcik go-librespot[16143]: time="2026-10-17T07:24:36+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:36 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:36 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:37 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:37 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:40 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4463. Oct 17 07:24:40 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:40 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:40 bcik go-librespot[16153]: go-librespot daemon starting... Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=debug msg="app state loaded" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+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]" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+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]" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+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]" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=info msg="zeroconf server listening on port 40231" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=debug msg="obtained new client token: AAGx2Zmte2/5wQaUd86hHNfeYoYDur45+Fey0HGFk/+lOhRR024qfd1WYAA+0Dh3QGFqbw5OqDfv36mR4hwL9S6ZY8e8mYTZmWm6EMczptKXkAMu7YPPTx3/5HYPv8RTimfcmOxelQSHZcRPkUWzvvziHj/1Ss8W0MMYNJcY0HRCEvtMBBaLPN/EaR9eT55wP1ety8+tpBoJOtsEz2m55mvKrK7Q11dAoJbB4s+LQxt1NA1cjsu0" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=debug msg="completed challenge" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:40 bcik go-librespot[16154]: time="2026-10-17T07:24:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:40 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:40 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:40 bcik volumio[15716]: info: BOOT COMPLETED Oct 17 07:24:40 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:40 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:43 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4464. Oct 17 07:24:43 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:43 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:43 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:43 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:43 bcik go-librespot[16178]: go-librespot daemon starting... Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=debug msg="app state loaded" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+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]" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+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]" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=info msg="zeroconf server listening on port 38337" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=debug msg="obtained new client token: AAEpdE1pGUpEnH0zpnz8+b2W5JOMoY28bIbExrIJQ6IHnaqxWPXOL+tRkIbBSQR5bVdA7IMa/nM2xHSxJyq/T5OOrGWBhZA6gAtZajNWCxjw0+QHSxdz6s1xomGUqPoiXpN6Gy4zh3MWjL/9jt5r7TnZ/90BEku/fsR68QKpxUaACmHveJD3GpnWi0AIncpm86rKymupuMWfhgxJ9FV32bOhAX76eoWg+UXGc6KOCh8JjLU5+ZgZut8=" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=debug msg="completed challenge" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:43 bcik go-librespot[16179]: time="2026-10-17T07:24:43+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:43 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:43 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:46 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:46 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:47 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4465. Oct 17 07:24:47 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:47 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:47 bcik go-librespot[16188]: go-librespot daemon starting... Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=debug msg="app state loaded" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+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]" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+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]" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+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]" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=info msg="zeroconf server listening on port 44479" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=debug msg="obtained new client token: AAExCGqoBx9qowJ4IB3xoKXz2tmffkje4ZJ7yYuGEoC4823E60tWpa2/9xQ6Z8XDNdcbYJwc7d2fw1KlyAcgaO2rGfT8j2riQU1M3CTWKbwLlD6StK1tUYhRNsxvCxcAREdhCxt3zBWZoG4IOMwYyAj4WNalJaJkF+SZvRtrRRIHrUDppaWMhxECNJjSLxXMrDNRNb6pSJF7OW2aCVonZIo5x5/C0oxBIVxkIRFglVyEBTSUTgtH" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+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" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=debug msg="completed challenge" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:47 bcik go-librespot[16189]: time="2026-10-17T07:24:47+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:47 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:47 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:49 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:49 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:50 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4466. Oct 17 07:24:50 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:50 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:50 bcik go-librespot[16199]: go-librespot daemon starting... Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=debug msg="app state loaded" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+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]" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+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]" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+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]" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=info msg="zeroconf server listening on port 38701" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=debug msg="obtained new client token: AAHVfGKLHs/I/l/teCqfCpSeHdh2+3z/aLTZ7ZPyoWqouWtWUryxTBMwmOif0OyC1hWyBy3jPCQhcz5P6WSJ+SsxiC9X7bwTUrbDyO3ZMysmBBNBFcu7oas3TuP54equEu76F6+NOMNBrxipHNUF0SNjtOqJvoJB6KGPFGy6U375Af/M1xfjAKq4RSUEwl4ujPyQchY3CV3h93MDZzQOzesZYpe+k3np9U0I30nUxq6JzyuXY6LR+KU=" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=debug msg="completed challenge" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:50 bcik go-librespot[16200]: time="2026-10-17T07:24:50+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:50 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:50 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:52 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:52 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:54 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4467. Oct 17 07:24:54 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:54 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:54 bcik go-librespot[16223]: go-librespot daemon starting... Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=debug msg="app state loaded" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+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]" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+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]" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+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]" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=info msg="zeroconf server listening on port 46373" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=debug msg="obtained new client token: AAHz09R/nY7YnSKa14cJ7sy86lfbicgd5xJmyO0K7KgShoiPpIiUL/n6G86NQ/wv4lRPlwWT6FLZbJwDcUrCIQy5zpOiimgRZYRA/0xUsHFOdOq8u5NzUTD2Q2pujRx2LFm+6TiDWKMA2XQf1N6c7WZPs+++AX78bmxn+T2ujIe8MRRBW+0XmMSL/bjoZRQeDhTthbhEirdGGHvMKk887UeMcJlIKO8De9t5DrLbdr+3Zk2R/ACz" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=debug msg="completed challenge" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:54 bcik go-librespot[16224]: time="2026-10-17T07:24:54+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:54 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:54 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:55 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:55 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:24:57 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4468. Oct 17 07:24:57 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:57 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:24:57 bcik go-librespot[16233]: go-librespot daemon starting... Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=debug msg="app state loaded" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+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]" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+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]" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+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]" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=info msg="zeroconf server listening on port 37425" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=debug msg="obtained new client token: AAHVlWdVVOdMb3kkbagFJ6ipF04YMRwisxwEXUXnt9FxZaKY1eDXiROQXgiaiVePn0P7zPRxAuZ44pKsPFJKJsYSJy9VEYlgjBQ99EGi4XyVwQPoy8lT4loCeUfjekw0Otfpdx5FkUVgTN7cq5dvukF+vJHcUgLwcHhKzJITHGHFsDEUdGYzeU2JcSKeKYB8G7VB/hMnzfCQQ9gMnOhuZxCTh5vba7hLpTqp2dijSWPQX03pp4ah8kU=" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+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" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=debug msg="completed keyexchange" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=debug msg="completed challenge" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:24:57 bcik go-librespot[16234]: time="2026-10-17T07:24:57+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:24:57 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:24:57 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:24:58 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:24:58 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:25:01 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4469. Oct 17 07:25:01 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:25:01 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:25:01 bcik go-librespot[16244]: go-librespot daemon starting... Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=debug msg="app state loaded" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+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]" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+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]" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+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]" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=info msg="zeroconf server listening on port 41939" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=debug msg="obtained new client token: AAHlBrQibHk3etcB9vnUcTMeoWNXgkS9z4ktWfZFtt0oG9lN6dFbljusE31/9CFOTUNo/mJlcV1DqedWbhGjdVeflfGkBI56VdTBzvDBsJy+Sr+xT37fY/etPpHt3aQog0ShUm84GH3qT5i5IviU4PcLl9CaPxo73paap7QKYSFYmkG9ypLZUu3ZlqJreEWt2TPpVvsu1EvHufhDoDO19khGApODQZjubvZ0EMM7fXArO9e+qWxF" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=debug msg="completed keyexchange" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=debug msg="completed challenge" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:25:01 bcik go-librespot[16245]: time="2026-10-17T07:25:01+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:25:01 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:25:01 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:25:01 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:25:01 bcik volumio[15716]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:25:04 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4470. Oct 17 07:25:04 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:25:04 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 17 07:25:04 bcik go-librespot[16268]: go-librespot daemon starting... Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=info msg="running go-librespot 0.6.2" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=debug msg="app state loaded" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 17 07:25:04 bcik volumio[15716]: info: Initializing connection to go-librespot Websocket Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=debug msg="new websocket client" Oct 17 07:25:04 bcik volumio[15716]: info: Connection to go-librespot Websocket established Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+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]" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+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]" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+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]" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=info msg="zeroconf server listening on port 42347" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=debug msg="obtained new client token: AAHqjESjRz06H3n54rD1ZY0JqBUiGpguy9gIkCy5JrRdHDZUnguKiXsM7tPE7d9d3BLDUArzlQwsIFarvSw07jLNANS+uwB8+31TKHD++EW1gMjpsr/Nr+cu1Nv8e3hXhGChfgREv0g/ktUzArcGMOglnlqj7wc69MV0bLa1skQfvxBgb09XAmS+dtLZ53C8hkM55+IX7Ko7zpcDdZD0bFgwwQ4/bwm3OxqRMkTOiarC10d8GuNU/50=" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=debug msg="completed keyexchange" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=debug msg="completed challenge" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=info msg="authenticated AP" username="la***pl" Oct 17 07:25:04 bcik go-librespot[16269]: time="2026-10-17T07:25:04+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 17 07:25:04 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 17 07:25:04 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 17 07:25:04 bcik volumio[15716]: info: Connection to go-librespot Websocket closed Oct 17 07:25:07 bcik volumio[15716]: info: Getting Spotify volume Oct 17 07:25:07 bcik volumio[15716]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 07:25:07 bcik volumio[15716]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 17 07:25:07 bcik volumio[15716]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 17 07:25:07 bcik volumio[15716]: errno: -111, Oct 17 07:25:07 bcik volumio[15716]: code: 'ECONNREFUSED', Oct 17 07:25:07 bcik volumio[15716]: syscall: 'connect', Oct 17 07:25:07 bcik volumio[15716]: address: '127.0.0.1', Oct 17 07:25:07 bcik volumio[15716]: port: 9879, Oct 17 07:25:07 bcik volumio[15716]: response: undefined Oct 17 07:25:07 bcik volumio[15716]: } Oct 17 07:25:07 bcik volumio[15716]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 07:25:07 bcik sudo[16292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 07:24' Oct 17 07:25:07 bcik sudo[16292]: 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"