Oct 14 03:01:00 bcik sudo[25664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 03:00' Oct 14 03:01:00 bcik sudo[25664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:00 bcik sudo[25664]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:00 bcik volumio-remote-updater[9031]: [2026-10-14 03:01:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 14 03:01:00 bcik volumio-remote-updater[9031]: [2026-10-14 03:01:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 14 03:01:00 bcik systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:00 bcik volumio5-onboarding[11680]: time=2026-10-14T03:01:00.155+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 14 03:01:00 bcik volumio5-onboarding[11680]: time=2026-10-14T03:01:00.158+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 14 03:01:00 bcik systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 14 03:01:00 bcik systemd[1]: volumio.service: Consumed 7.943s CPU time. Oct 14 03:01:00 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 14 03:01:00 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 14 03:01:00 bcik systemd[1]: volumio.service: Scheduled restart job, restart counter is at 24213. Oct 14 03:01:00 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 14 03:01:00 bcik systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 14 03:01:00 bcik systemd[1]: volumio.service: Consumed 7.943s CPU time. Oct 14 03:01:00 bcik systemd[1]: Started volumio.service - Volumio Backend Module. Oct 14 03:01:00 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 14 03:01:00 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:00 bcik volumio[25704]: info: ----- Volumio3 ---- Oct 14 03:01:00 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:00 bcik volumio[25704]: info: ----- System startup ---- Oct 14 03:01:00 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:00 bcik volumio[25704]: info: MYVOLUMIO Environment detected Oct 14 03:01:00 bcik volumio[25704]: info: Plugin folders cleanup Oct 14 03:01:00 bcik volumio[25704]: info: Scanning into folder /volumio/app/plugins/ Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category audio_interface Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category miscellanea Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category music_service Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category plugins.json Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category system_controller Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category user_interface Oct 14 03:01:00 bcik volumio[25704]: info: Scanning into folder /data/plugins/ Oct 14 03:01:00 bcik volumio[25704]: info: Scanning category music_service Oct 14 03:01:00 bcik volumio[25704]: info: Plugin folders cleanup completed Oct 14 03:01:00 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:00 bcik volumio[25704]: info: ----- Core plugins startup ---- Oct 14 03:01:00 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:00 bcik volumio[25704]: info: Loading plugins from folder /volumio/app/plugins/ Oct 14 03:01:00 bcik volumio[25704]: info: Adding plugin upnp to MyMusic Plugins Oct 14 03:01:00 bcik volumio[25704]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 14 03:01:00 bcik volumio[25704]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 14 03:01:00 bcik volumio[25704]: info: Loading plugins from folder /data/plugins/ Oct 14 03:01:00 bcik volumio[25704]: info: Loading plugin "system"... Oct 14 03:01:00 bcik volumio[25704]: info: Loading plugin "appearance"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "network"... Oct 14 03:01:01 bcik volumio[25704]: info: Refreshing Cached IP Addresses Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "services"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "volumio5onboarding"... Oct 14 03:01:01 bcik sudo[25732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 03:01:01 bcik sudo[25734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 03:01:01 bcik sudo[25734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:01 bcik sudo[25732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:01 bcik sudo[25732]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:01 bcik sudo[25734]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "alsa_controller"... Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "wizard"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "networkfs"... Oct 14 03:01:01 bcik volumio[25704]: info: Starting Udev Watcher for removable devices Oct 14 03:01:01 bcik volumio[25704]: info: Ignoring mount for partition: boot Oct 14 03:01:01 bcik volumio[25704]: info: Ignoring mount for partition: volumio Oct 14 03:01:01 bcik volumio[25704]: info: Ignoring mount for partition: volumio_data Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "volumio_command_line_client"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "upnp"... Oct 14 03:01:01 bcik volumio[25704]: info: [1791939661111] Starting Upmpd Daemon Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "my_music"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "mpd"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "upnp_browser"... Oct 14 03:01:01 bcik volumio[25704]: info: Starting UPNP Browser Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "alarm-clock"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "airplay_emulation"... Oct 14 03:01:01 bcik volumio[25704]: info: Starting Shairport Sync Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "last_100"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "webradio"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "i2s_dacs"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "volumiodiscovery"... Oct 14 03:01:01 bcik volumio[25704]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 14 03:01:01 bcik node[25704]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 14 03:01:01 bcik volumio[25704]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 03:01:01 bcik node[25704]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 03:01:01 bcik volumio[25704]: *** WARNING *** For more information see Oct 14 03:01:01 bcik node[25704]: *** WARNING *** For more information see Oct 14 03:01:01 bcik volumio[25704]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 14 03:01:01 bcik node[25704]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 14 03:01:01 bcik volumio[25704]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 03:01:01 bcik node[25704]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 14 03:01:01 bcik volumio[25704]: *** WARNING *** For more information see Oct 14 03:01:01 bcik node[25704]: *** WARNING *** For more information see Oct 14 03:01:01 bcik volumio[25704]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 14 03:01:01 bcik volumio[25704]: info: Discovery: Started advertising with name: Bącik Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "spop"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "outputs"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "albumart"... Oct 14 03:01:01 bcik volumio[25704]: info: Plugin example_plugin is not enabled Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "inputs"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "updater_comm"... Oct 14 03:01:01 bcik volumio[25704]: info: Plugin mpdemulation is not enabled Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "rest_api"... Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "websocket"... Oct 14 03:01:01 bcik volumio[25704]: info: Starting Socket.io Server version 1.7.4 Oct 14 03:01:01 bcik volumio[25704]: info: Loading plugin "radio_browser"... Oct 14 03:01:01 bcik volumio[25765]: Forking 3 albumart workers Oct 14 03:01:01 bcik volumio[25704]: info: Loading i18n strings for locale pl Oct 14 03:01:01 bcik volumio[25704]: Updating browse sources language Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::initPlayerControls Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: Express server listening on port 3000 Oct 14 03:01:01 bcik volumio[25704]: [Metrics] WebUI: 1s 301.95ms Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::resetVolumioState Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::getcurrentVolume Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 03:01:01 bcik volumio[25704]: info: Volumio Network Manager: Network status updated: 1 Oct 14 03:01:01 bcik volumio[25704]: 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 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:01 bcik volumio[25704]: 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 14 03:01:01 bcik volumio[25704]: info: Reloading queue from file Oct 14 03:01:01 bcik volumio[25704]: info: VolumeController:: Volume=41 Mute =false Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::pushState Oct 14 03:01:01 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::volumioPushState Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::updateTrackBlock Oct 14 03:01:01 bcik volumio[25704]: info: CorePlayQueue::getTrackBlock Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::setRepeat false single undefined Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::pushState Oct 14 03:01:01 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::volumioPushState Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::setRandom false Oct 14 03:01:01 bcik volumio[25704]: info: CoreStateMachine::pushState Oct 14 03:01:01 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 03:01:01 bcik volumio[25704]: info: CoreCommandRouter::volumioPushState Oct 14 03:01:01 bcik volumio[25704]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 14 03:01:01 bcik volumio[25704]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04171 Oct 14 03:01:01 bcik volumio[25704]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 14 03:01:01 bcik volumio[25704]: 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 14 03:01:01 bcik volumio[25704]: info: Setting Device type: Raspberry PI Oct 14 03:01:02 bcik volumio[25704]: info: Completed loading Core Plugins Oct 14 03:01:02 bcik volumio[25704]: info: Preparing to generate the ALSA configuration file Oct 14 03:01:02 bcik volumio[25704]: 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 14 03:01:02 bcik volumio[25704]: info: VolumeController:: Volume=41 Mute =false Oct 14 03:01:02 bcik volumio[25704]: info: CoreStateMachine::pushState Oct 14 03:01:02 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioPushState Oct 14 03:01:02 bcik volumio[25704]: 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 14 03:01:02 bcik volumio[25704]: 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 14 03:01:02 bcik volumio[25704]: info: Asound.conf file unchanged, so no further update is needed Oct 14 03:01:02 bcik volumio[25704]: info: Output device has changed, restarting MPD Oct 14 03:01:02 bcik sudo[25822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 03:01:02 bcik volumio[25704]: info: Output device has changed, restarting Shairport Sync Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:02 bcik sudo[25822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik sudo[25822]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik volumio[25704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 03:01:02 bcik volumio[25704]: info: ___________ START PLUGINS ___________ Oct 14 03:01:02 bcik sudo[25825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 03:01:02 bcik sudo[25825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik volumio[25704]: info: ControllerMpd::onStart: Initializing MPD Oct 14 03:01:02 bcik volumio[25704]: info: Creating MPD Configuration file Oct 14 03:01:02 bcik sudo[25830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 14 03:01:02 bcik sudo[25830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 03:01:02 bcik volumio[25704]: info: [1791939662066] CoreMusicLibrary::Adding element Serwery Mediów Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 03:01:02 bcik volumio[25704]: info: UPNP Browser: Client initialized successfully Oct 14 03:01:02 bcik sudo[25833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 03:01:02 bcik sudo[25833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik sudo[25836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 03:01:02 bcik sudo[25836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:02 bcik sudo[25833]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik sudo[25830]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 03:01:02 bcik volumio[25704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:02 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 14 03:01:02 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 03:01:02 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 14 03:01:02 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 03:01:02 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 03:01:02 bcik volumio[25704]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 03:01:02 bcik volumio[25704]: info: [1791939662114] CoreMusicLibrary::Adding element Last_100 Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 03:01:02 bcik volumio[25704]: info: [1791939662115] CoreMusicLibrary::Adding element Webradio Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 03:01:02 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 03:01:02 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 03:01:02 bcik volumio[25704]: info: Initializing BBC Radios Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: Creating Spotify config file Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik sudo[25849]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 03:01:02 bcik sudo[25849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 03:01:02 bcik sudo[25849]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik volumio[25775]: Starting albumart workers Oct 14 03:01:02 bcik volumio[25776]: Starting albumart workers Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 14 03:01:02 bcik volumio[25704]: info: [1791939662245] CoreMusicLibrary::Adding element Radio Browser Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 03:01:02 bcik volumio[25704]: Cannot find translation for source Radio Browser Oct 14 03:01:02 bcik volumio[25704]: info: Volumio Calling Home Oct 14 03:01:02 bcik volumio[25704]: 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 14 03:01:02 bcik volumio[25704]: info: MPD Permissions set Oct 14 03:01:02 bcik volumio[25704]: info: MPD Permissions set Oct 14 03:01:02 bcik volumio[25777]: Starting albumart workers Oct 14 03:01:02 bcik volumio[25704]: info: Spotify config file written Oct 14 03:01:02 bcik sudo[25882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 14 03:01:02 bcik sudo[25882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik volumio[25704]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 14 03:01:02 bcik volumio[25704]: info: Volumio called home Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:02 bcik volumio[25704]: info: No need to fix Spotify hosts Oct 14 03:01:02 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:02 bcik go-librespot[25890]: go-librespot daemon starting... Oct 14 03:01:02 bcik sudo[25882]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=debug msg="app state loaded" Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: info: Starting Shairport Sync Oct 14 03:01:02 bcik volumio[25704]: info: Starting Shairport Sync Oct 14 03:01:02 bcik volumio[25704]: info: Starting Shairport Sync Oct 14 03:01:02 bcik sudo[25903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 03:01:02 bcik sudo[25903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik sudo[25905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 03:01:02 bcik sudo[25905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik sudo[25907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 03:01:02 bcik sudo[25907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:02 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 03:01:02 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 03:01:02 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 03:01:02 bcik systemd[1]: shairport-sync.service: Consumed 1.991s CPU time. Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+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 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+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 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+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 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=info msg="zeroconf server listening on port 34787" Oct 14 03:01:02 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 03:01:02 bcik sudo[25905]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik sudo[25903]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 03:01:02 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 03:01:02 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 03:01:02 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 03:01:02 bcik volumio[25704]: info: An error occurred while refreshing Spotify Token Error: Bad Request Oct 14 03:01:02 bcik sudo[25907]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=debug msg="obtained new client token: AAEVW/uqLngLPzR+stSRZrv3PkIkn1ZJY2hrjisP1NU0QnEjGucpmYk+ZsWNkmMmvSBG5sIalk0NUq+qA96+k+Zqn+zRyyYEB+zrxNZylBI+OQ1VYukuSFcBTQDBvclws4OMyVRvxMbDQmrHl2UobOTql/KpXUYhSmywpixRCpX8toHMOBz7tyTGkjx/aLUp0eK0rdusZu1+Ql5eHJHgsqnN5hn87Z20TmKyQJdRx75tiZrHVwtUGDw=" Oct 14 03:01:02 bcik volumio[25704]: info: Shairport-Sync Started Oct 14 03:01:02 bcik volumio[25704]: Error adding Membership: Error: addMembership EINVAL Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioGetState Oct 14 03:01:02 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:02 bcik volumio[25704]: info: Shairport-Sync Started Oct 14 03:01:02 bcik volumio[25704]: info: Shairport-Sync Started Oct 14 03:01:02 bcik volumio[25704]: info: Discovery: adding f1ed5fb6-e21d-47c8-819b-29842600355e Oct 14 03:01:02 bcik volumio[25704]: info: Discovery: Found device Bącik Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioGetState Oct 14 03:01:02 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: info: Discovery: this is already registered, f1ed5fb6-e21d-47c8-819b-29842600355e Oct 14 03:01:02 bcik volumio[25704]: info: Discovery: Found device Bącik Oct 14 03:01:02 bcik volumio[25704]: info: CoreCommandRouter::volumioGetState Oct 14 03:01:02 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=debug msg="completed challenge" Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik go-librespot[25895]: time="2026-10-14T03:01:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:02 bcik mpd[25865]: 2026-10-14T03:01:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik systemd[1]: Started mpd.service - Music Player Daemon. Oct 14 03:01:02 bcik sudo[25836]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:02 bcik sudo[25825]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:02 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:02 bcik volumio[25704]: info: Completed starting Core Plugins Oct 14 03:01:02 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:02 bcik volumio[25704]: info: ----- MyVolumio plugins startup ---- Oct 14 03:01:02 bcik volumio[25704]: info: ------------------------------------------- Oct 14 03:01:02 bcik volumio[25704]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: error: MPD error: The expression evaluated to a falsy value: Oct 14 03:01:02 bcik volumio[25704]: assert.ok(self.idling) Oct 14 03:01:02 bcik volumio[25704]: error: The expression evaluated to a falsy value: Oct 14 03:01:02 bcik volumio[25704]: assert.ok(self.idling) Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: info: MPD running with PID25865 Oct 14 03:01:02 bcik volumio[25704]: ,establishing connection Oct 14 03:01:02 bcik volumio[25704]: error: updateQueue error: null Oct 14 03:01:02 bcik volumio[25704]: 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: 2 Oct 14 03:01:02 bcik volumio[25704]: error: updateQueue error: null Oct 14 03:01:05 bcik volumio-remote-updater[9031]: [2026-10-14 03:01:05] [connect] Successful connection Oct 14 03:01:05 bcik volumio-remote-updater[9031]: [2026-10-14 03:01:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791939665 101 Oct 14 03:01:05 bcik volumio[25704]: 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 14 03:01:05 bcik volumio[25704]: info: go-librespot daemon successfully initialized Oct 14 03:01:05 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4283. Oct 14 03:01:05 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:05 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:05 bcik go-librespot[25941]: go-librespot daemon starting... Oct 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+02:00" level=debug msg="app state loaded" Oct 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+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 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+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 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+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 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+02:00" level=info msg="zeroconf server listening on port 37587" Oct 14 03:01:05 bcik go-librespot[25942]: time="2026-10-14T03:01:05+02:00" level=debug msg="obtained new client token: AAHsDgpOL5tPmv/4e7tOUql53uoimq3olucK7SA84mDicpnMHZ95WW+2B7OQPVGrFst7CVVwlfMwBvgyA/KAXk6txYvHnG+ZN4ixfs2yHXtmH/Ru6XZO6ENe3Fi8RyGw5VX31ERCkAE64e/Embml+9Qbtemq4Faf9QkDVQ6D+Y78ke9+Drdufisid4yHHWlYOX19w24fZhYl7Tnexwp1uwNMZ5uq85qVvwVoLyX391Cozco349DiGmg=" Oct 14 03:01:06 bcik go-librespot[25942]: time="2026-10-14T03:01:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:06 bcik go-librespot[25942]: time="2026-10-14T03:01:06+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:06 bcik go-librespot[25942]: time="2026-10-14T03:01:06+02:00" level=debug msg="completed challenge" Oct 14 03:01:06 bcik go-librespot[25942]: time="2026-10-14T03:01:06+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:06 bcik go-librespot[25942]: time="2026-10-14T03:01:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:06 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:06 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:07 bcik volumio[25704]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 14 03:01:08 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:08 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:09 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4284. Oct 14 03:01:09 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:09 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:09 bcik go-librespot[25951]: go-librespot daemon starting... Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=debug msg="app state loaded" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+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 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+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 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+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 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=info msg="zeroconf server listening on port 33709" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=debug msg="obtained new client token: AAGpBLiehLlCXYQ5EUog4vZPiCVil//LRR+meTgsM8it1DSjtOnyrUP0tyM2poCUd6kR8Z7HVsjAbeih1DFbpk2c3hwC1MgUCR9qsV84SnNHVH8Kj2nfs+eMlLg4matKxRNyKTgZoeeFOrIuRnNQU6VbtDutaGbmT+Z2oc5Q8tFuPqEfdT7IQ1MocQXE8qryxY03pLeIVlfND5luMknRCBFLLptY6/cDs5YIeyg46nZEGkALgdGkBIc=" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=debug msg="completed challenge" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:09 bcik go-librespot[25952]: time="2026-10-14T03:01:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:09 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:09 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:11 bcik sudo[25976]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 03:01:11 bcik sudo[25976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:11 bcik sudo[25976]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:11 bcik sudo[25978]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 03:01:11 bcik sudo[25978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:11 bcik sudo[25978]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:11 bcik sudo[25980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 14 03:01:11 bcik sudo[25980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:11 bcik sudo[25980]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:11 bcik volumio[25704]: info: Upmpdcli Daemon Started Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin bluetooth to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin multiroom to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin metavolumio to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin cd_controller to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 14 03:01:11 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:11 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:11 bcik volumio[25704]: info: Starting MyVolumio Remote Streaming Endpoints Oct 14 03:01:11 bcik volumio[25704]: info: MyVolumio login type: Token Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 14 03:01:11 bcik volumio[25704]: info: Streaming services startup Oct 14 03:01:11 bcik volumio[25704]: info: Starting Streaming Daemon Oct 14 03:01:11 bcik sudo[25985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 14 03:01:11 bcik volumio[25704]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 14 03:01:11 bcik sudo[25985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:11 bcik sudo[25985]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:11 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:11 bcik volumio[25704]: error: Cannot start Volumio Streaming Daemon Oct 14 03:01:11 bcik volumio[25704]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 14 03:01:11 bcik volumio[25704]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 14 03:01:11 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:12 bcik volumio[25704]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 14 03:01:12 bcik volumio[25704]: info: MyVolumio token set successfully Oct 14 03:01:12 bcik volumio[25704]: info: MYVOLUMIO: Adding device Oct 14 03:01:12 bcik volumio[25704]: info: MYVOLUMIO: Evaluating Server Oct 14 03:01:12 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4285. Oct 14 03:01:12 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:12 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:12 bcik go-librespot[25998]: go-librespot daemon starting... Oct 14 03:01:12 bcik volumio[25704]: info: MyVolumio status changed Oct 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+02:00" level=debug msg="app state loaded" Oct 14 03:01:12 bcik volumio[25704]: info: Streaming services startup Oct 14 03:01:12 bcik volumio[25704]: info: Starting Streaming Daemon Oct 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:12 bcik volumio[25704]: info: Removing browser output: myVolumio user plan is not superstar Oct 14 03:01:12 bcik volumio[25704]: info: Removing audio output: Oct 14 03:01:12 bcik volumio[25704]: info: Stoppping Tunnel 1 Oct 14 03:01:12 bcik sudo[26021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 14 03:01:12 bcik sudo[26021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:12 bcik sudo[26023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 14 03:01:12 bcik sudo[26023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 03:01:12 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 14 03:01:12 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 14 03:01:12 bcik sudo[26021]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:12 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 14 03:01:12 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 14 03:01:12 bcik volumio[25704]: error: Cannot start Volumio Streaming Daemon Oct 14 03:01:12 bcik volumio[25704]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 14 03:01:12 bcik volumio[25704]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 14 03:01:12 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 14 03:01:12 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 14 03:01:12 bcik sudo[26023]: pam_unix(sudo:session): session closed for user root Oct 14 03:01:12 bcik volumio[25704]: info: Remote SSH Stopped Oct 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+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 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+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 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+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 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+02:00" level=info msg="zeroconf server listening on port 39083" Oct 14 03:01:12 bcik go-librespot[26013]: time="2026-10-14T03:01:12+02:00" level=debug msg="obtained new client token: AAElJflIRhTCOEJuQepCelCewOoNsYNeNWTj/lMt6NaTdeURyr8N2bKt79U61MkSXK+granTIJzAKkKc+WU+dk0fMz7UcvpaEbCiwGBbyl7l+dtAsuy8sXJXfGr3RZ2dOSUQ0gFxjY9RQh5IHhJfyPiEoFaN90ZOMFBbEC4JMPVOT5e5e6xbfWmYeq5myr3yAqS+8m463muvUiJXFjEFgfV/+JnM0yDrRn8zeVcKoxKUVami9+fT8N8=" Oct 14 03:01:13 bcik go-librespot[26013]: time="2026-10-14T03:01:13+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 14 03:01:13 bcik go-librespot[26013]: time="2026-10-14T03:01:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 14 03:01:13 bcik go-librespot[26013]: time="2026-10-14T03:01:13+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:13 bcik go-librespot[26013]: time="2026-10-14T03:01:13+02:00" level=debug msg="completed challenge" Oct 14 03:01:13 bcik go-librespot[26013]: time="2026-10-14T03:01:13+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:13 bcik go-librespot[26013]: time="2026-10-14T03:01:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:13 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:13 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:13 bcik volumio[25704]: info: Setting Geolocation for MyVolumio to eu6 Oct 14 03:01:13 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:13 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:13 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:13 bcik volumio[25704]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 14 03:01:14 bcik volumio[25704]: info: Updating MyVolumio device info Oct 14 03:01:14 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:14 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:14 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:14 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:14 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:14 bcik volumio[25704]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 14 03:01:16 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4286. Oct 14 03:01:16 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:16 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:16 bcik go-librespot[26029]: go-librespot daemon starting... Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=debug msg="app state loaded" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+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 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+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 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=info msg="zeroconf server listening on port 44363" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=debug msg="obtained new client token: AAFtvXOMZVLqCfGeujCOMI9iOpg/FHSX/XoMl23xADSDNWPy2XmR4OXjKbQAqhOtv9wqbtVv3KqY0NkzBrfx1VwwmH8FoL6ghvWLaKUjzmaP0theg0weAiNgeOipu/DxNWT9gORg1excR+YCrY6XkSTrhuIrbMqP0H+JIGnE2Jjw6iUbjhvGhO2+xPkzXKwYq49E2i/4Oqge+wBoswjVPlAYW1PdSOd6a7SfigTa9fGwChvQrWJJtqI=" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+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 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=debug msg="completed challenge" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:16 bcik go-librespot[26030]: time="2026-10-14T03:01:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:16 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:16 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:17 bcik volumio[25704]: info: MYVOLUMIO: Adding device Oct 14 03:01:17 bcik volumio[25704]: info: MYVOLUMIO: Evaluating Server Oct 14 03:01:17 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:17 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:17 bcik volumio[25704]: info: Setting Geolocation for MyVolumio to eu6 Oct 14 03:01:17 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:17 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:17 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:18 bcik volumio[25704]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 14 03:01:18 bcik volumio[25704]: info: Updating MyVolumio device info Oct 14 03:01:18 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:18 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:18 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:19 bcik volumio[25704]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 14 03:01:19 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4287. Oct 14 03:01:19 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:19 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:19 bcik go-librespot[26059]: go-librespot daemon starting... Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+02:00" level=debug msg="app state loaded" Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+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 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+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 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+02:00" level=info msg="zeroconf server listening on port 41485" Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+02:00" level=debug msg="obtained new client token: AAGcGFPLURwGAokoh2MvXm4W7Z1Go+6kFZPsfZW1/sOPytSo69FyYRXRokLSrR48V/0Y5b/CFTWmEb3JhYs07qF4ZyK2xJMA+60EzcA5Q0jlCEzZNzR6Iy5AcPf3Tsj3v8mTTkTJ6CVF9jTMZ3AnbnPfMEsNKIldaK1rkXtS2NJNR/7QsJNcKjJRiYCNySGDhcUf4wXjDZITfE0K3TQaR5zMjkJECI1/ppa1ovVyZgKxqUATITOfB+g=" Oct 14 03:01:19 bcik go-librespot[26060]: time="2026-10-14T03:01:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:20 bcik go-librespot[26060]: time="2026-10-14T03:01:20+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:20 bcik go-librespot[26060]: time="2026-10-14T03:01:20+02:00" level=debug msg="completed challenge" Oct 14 03:01:20 bcik go-librespot[26060]: time="2026-10-14T03:01:20+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:20 bcik go-librespot[26060]: time="2026-10-14T03:01:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:20 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:20 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:20 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:20 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:23 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4288. Oct 14 03:01:23 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:23 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:23 bcik go-librespot[26084]: go-librespot daemon starting... Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=debug msg="app state loaded" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+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 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+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 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+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 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=info msg="zeroconf server listening on port 40163" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=debug msg="obtained new client token: AAGaC6yxWBLtBSM/mIMH89yKAiF/cE3XbWfoSMecRecCnbcqAlSgu42q3x//a/xy5Bs2FUwxIBa3jmFuN6lZx6SZDA1il886DEujsQhmgojyar4pSK+n8LFaXS4OXZMOLI5Knyc3rYauO2GDLkINlfxY9XLb7QGLZqNgXInYmNLiUQhbCpclq0RT6O84Hdf+QSLhbclGg8jWTIeLJqHMCuIgq0h5lPQKjOvbilMEsPw2TV/kJfliB4U=" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=debug msg="completed challenge" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:23 bcik go-librespot[26085]: time="2026-10-14T03:01:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:23 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:23 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:23 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:23 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:26 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 03:01:26 bcik volumio[25704]: 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 14 03:01:26 bcik volumio[25704]: info: CoreCommandRouter::volumioGetState Oct 14 03:01:26 bcik volumio[25704]: info: CorePlayQueue::getTrack 0 Oct 14 03:01:26 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:26 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:26 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4289. Oct 14 03:01:26 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:26 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:26 bcik go-librespot[26094]: go-librespot daemon starting... Oct 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+02:00" level=debug msg="app state loaded" Oct 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+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 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+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 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+02:00" level=info msg="zeroconf server listening on port 33675" Oct 14 03:01:26 bcik go-librespot[26095]: time="2026-10-14T03:01:26+02:00" level=debug msg="obtained new client token: AAEeO0wuGDmStgbras5mVAF2LeKnB0YUVV9KaCZsUldqCID8OXNhGK8bjQmOK5Txiz/Hd/QEX362yXeQGa4R2C5FRNPv1z2zF3oXItpWfZsgpZVatOrAXDEIEgtGoId6Y8EPKH5Azt8TAgO6kQcQmTbDnckMlwbpGYUFPOiXwvwGrkxCo4FN4OObCqaHayCh9RAPGWQBA15v/94HEzbw5Y4yI4d/b5Dkknw5lV9LJnwgZo4nyO27GXg=" Oct 14 03:01:27 bcik go-librespot[26095]: time="2026-10-14T03:01:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:27 bcik go-librespot[26095]: time="2026-10-14T03:01:27+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:27 bcik go-librespot[26095]: time="2026-10-14T03:01:27+02:00" level=debug msg="completed challenge" Oct 14 03:01:27 bcik go-librespot[26095]: time="2026-10-14T03:01:27+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:27 bcik go-librespot[26095]: time="2026-10-14T03:01:27+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:27 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:27 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:29 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:29 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:30 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4290. Oct 14 03:01:30 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:30 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:30 bcik go-librespot[26104]: go-librespot daemon starting... Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=debug msg="app state loaded" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+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 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+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 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+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 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=info msg="zeroconf server listening on port 45957" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=debug msg="obtained new client token: AAHMnplGi8WpsBdAxY//D1J5KK64chIbf4dp5t2hhah3r1K0cYHPDvOAEj8k5zZEdyFBzmnHUZtoj843JSRjvmIDUuwibfqJJ3D147zMDqzL057QzSynTLj2IdMlTzAeHwhabPNPyOAZ7rWwZd0fzxLeNoiK8geL59gLuUWoE4lRfuvhww2RRkfpvMH+6Wgr6M6hl/amajDEDG9+BnAb+S5CWE3P5LfKkrG3Zb8r6sQpDln5KIchiQw=" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=debug msg="completed challenge" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:30 bcik go-librespot[26105]: time="2026-10-14T03:01:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:30 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:30 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::volumioGetBrowseSources Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 03:01:31 bcik volumio[25704]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 14 03:01:31 bcik volumio[25704]: info: Completed starting MyVolumio Plugin Oct 14 03:01:31 bcik volumio[25704]: [Metrics] CommandRouter: 30s 895.42ms Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::volumiosetStartupVolume Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::Close All Modals sent Oct 14 03:01:31 bcik volumio[25704]: info: CoreCommandRouter::Close All Modals sent Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 03:01:32 bcik volumio-remote-updater[9031]: Test mode disabled Oct 14 03:01:32 bcik volumio-remote-updater[9031]: Alpha mode disabled Oct 14 03:01:32 bcik volumio-remote-updater[9031]: Alpha legacy test mode disabled Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 14 03:01:32 bcik volumio[25704]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 03:01:32 bcik volumio[25704]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 14 03:01:32 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:32 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:33 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4291. Oct 14 03:01:33 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:33 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:33 bcik go-librespot[26134]: go-librespot daemon starting... Oct 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+02:00" level=debug msg="app state loaded" Oct 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+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 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+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 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+02:00" level=info msg="zeroconf server listening on port 42461" Oct 14 03:01:33 bcik go-librespot[26135]: time="2026-10-14T03:01:33+02:00" level=debug msg="obtained new client token: AAEqJzXCaGjFdG9KXda4XB5+aBTXIj+lzJC3mPf7byHXaafUYJxE+CFOaL70a80k78yMSU/iA2DwLD/cG/psaYWHqTNe/6qEDnJr99Z5QqU46YVKYVJqVlnvgVIbTERYhGhggMTPjWpFtpX3GOvvnQLwXxhjvQwmmxurhcFgwsElj8/pPJhsy6WpFM9+yXu0cym7OvZG/nJ2JG3P+q1F1Ln9vAnuT9vChHNZM42J8U/jXOky1B8NZJY=" Oct 14 03:01:34 bcik go-librespot[26135]: time="2026-10-14T03:01:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:34 bcik go-librespot[26135]: time="2026-10-14T03:01:34+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:34 bcik go-librespot[26135]: time="2026-10-14T03:01:34+02:00" level=debug msg="completed challenge" Oct 14 03:01:34 bcik go-librespot[26135]: time="2026-10-14T03:01:34+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:34 bcik go-librespot[26135]: time="2026-10-14T03:01:34+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:34 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:34 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:35 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:35 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:37 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4292. Oct 14 03:01:37 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:37 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:37 bcik go-librespot[26144]: go-librespot daemon starting... Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=debug msg="app state loaded" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+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 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+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 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+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 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=info msg="zeroconf server listening on port 34979" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=debug msg="obtained new client token: AAEk8nYZkgKb5EZO+/jWy4pwJPXSCnYKo224OjFZ+Xvfwp+rIvcUqAaf4Xtf9H/YSq8PtG1CIPSnUA+b4GrWlnAg80Yzmwj/1KTVL3HMOvPzK0Tfe5ghgG2m8Um2pnDcFTmO6yq/Yd/bXOX04q2ck6vgmGALCQhOanF4phvEcvUOl43E6qUlPzc/OHp+avyzDN+p1lusvsi92RnM1KHqxUET6w6JBXnGC+cHSoqjqSwP3S7bbXJUsOg=" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=debug msg="completed challenge" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:37 bcik go-librespot[26145]: time="2026-10-14T03:01:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:37 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:37 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:38 bcik volumio[25704]: info: BOOT COMPLETED Oct 14 03:01:38 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:38 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:40 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4293. Oct 14 03:01:40 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:40 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:40 bcik go-librespot[26155]: go-librespot daemon starting... Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+02:00" level=debug msg="app state loaded" Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+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 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+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 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+02:00" level=info msg="zeroconf server listening on port 45257" Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+02:00" level=debug msg="obtained new client token: AAEu05UC0d2GM8APsh9SkE3pjotLxwLAhkjk/J88QeIgnKLNLv+gE6Way0mledgFLkCo57NCE9o/UAsvAQR0AN5Pnvge+ZyxmdsyyFWHl2dMm7Sd8EIEfLa/USFxYOBqLnmp3sLfudprXTQK8LzZggr58LjECxrpxFqSo3Y/0AyCvuYUPvbgkQz9br4AfVaEICLhMzjP6qntg2+VYFb2o62rQdNvqwihp0CJ9jxKFsmznijLV8bmuRA=" Oct 14 03:01:40 bcik go-librespot[26156]: time="2026-10-14T03:01:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:41 bcik go-librespot[26156]: time="2026-10-14T03:01:41+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:41 bcik go-librespot[26156]: time="2026-10-14T03:01:41+02:00" level=debug msg="completed challenge" Oct 14 03:01:41 bcik go-librespot[26156]: time="2026-10-14T03:01:41+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:41 bcik go-librespot[26156]: time="2026-10-14T03:01:41+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:41 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:41 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:41 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:41 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:44 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4294. Oct 14 03:01:44 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:44 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:44 bcik go-librespot[26180]: go-librespot daemon starting... Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=debug msg="app state loaded" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+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 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+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 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+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 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=info msg="zeroconf server listening on port 44641" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=debug msg="obtained new client token: AAH3kun0ZdmJ5keURXSpilss8P+ZNmfsBLlDHU/WIr8CHCG1TXMU/gHyfk8lHEu5g4vavxQ4fCk0Q6L0SK5Uw0QE07CO4QgMbBpTfPwe0fK+QOF7DDLaT3Qaw7rJ3lNJyNwpq8ymZ7IsOrJwcbt4sU5qkKdX7D/uQAbzTshF7F6QadL5eUPkRqiOexUsQvHZVpjHS0WaAJ+UQo7tj2ay688xD/5IDjmTGoP/CI0QZuAS6vvMlO9lmyo=" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=debug msg="completed challenge" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:44 bcik go-librespot[26181]: time="2026-10-14T03:01:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:44 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:44 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:44 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:44 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:47 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:47 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:47 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4295. Oct 14 03:01:47 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:47 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:47 bcik go-librespot[26190]: go-librespot daemon starting... Oct 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+02:00" level=debug msg="app state loaded" Oct 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+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 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+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 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+02:00" level=info msg="zeroconf server listening on port 46435" Oct 14 03:01:47 bcik go-librespot[26191]: time="2026-10-14T03:01:47+02:00" level=debug msg="obtained new client token: AAFttJ+BQTkfZ2k8sVlcuVj/4RaohdFz4s9JgTFwKe5YVTnmtoYPJsvnX2xoKOxwAWp0kA9nIRHCbgSqaiHZ9ToWtVCaJxvKSb0T5VsOCrwfIkU1f15uurxx/oxU2llbEOPdOm59oSACF8/MQWa6KOpZjVnUo72gKriV7E905muSQUt6z1V/eNjFC6FE0LIVkEkgTxf/dq0c/zjlQ+PDuXKfbcctNxvIilIWXMJHbjHR94sqkt6hFqY=" Oct 14 03:01:48 bcik go-librespot[26191]: time="2026-10-14T03:01:48+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:48 bcik go-librespot[26191]: time="2026-10-14T03:01:48+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:48 bcik go-librespot[26191]: time="2026-10-14T03:01:48+02:00" level=debug msg="completed challenge" Oct 14 03:01:48 bcik go-librespot[26191]: time="2026-10-14T03:01:48+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:48 bcik go-librespot[26191]: time="2026-10-14T03:01:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:48 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:48 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:50 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:50 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:51 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4296. Oct 14 03:01:51 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:51 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:51 bcik go-librespot[26214]: go-librespot daemon starting... Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=debug msg="app state loaded" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+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 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+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 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+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 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=info msg="zeroconf server listening on port 45569" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=debug msg="obtained new client token: AAEFUnOIM1uTNZ6bGRUNvwlV+0ZhOBWefsfKTzYnemAaW0FrlxbAz3tanW1pS+VoBnd8x4cSJFCpk+70UorWh/ZLNcI+99lwLPaXPR+wY7l16jkNfz8PgwAAgNd3PNuWwEFcJOM20bqmKgOHQdYAnaXA5jn/kWBzD69qgzhB5RgxCdzJXWmyVwXGjGU2DQCAvj1DvEwQeEJdfxqXCr9JFhw0cptNzfwAm3B5KBz40+PT85Txr8Gm8gU=" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=debug msg="completed challenge" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:51 bcik go-librespot[26215]: time="2026-10-14T03:01:51+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:51 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:51 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:53 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:53 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:54 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4297. Oct 14 03:01:54 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:54 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:54 bcik go-librespot[26224]: go-librespot daemon starting... Oct 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+02:00" level=debug msg="app state loaded" Oct 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+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 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+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 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+02:00" level=info msg="zeroconf server listening on port 43131" Oct 14 03:01:54 bcik go-librespot[26225]: time="2026-10-14T03:01:54+02:00" level=debug msg="obtained new client token: AAGGHV09zhkMFLoh7+xVXGe77Y62iH2nzYFflcVpMf70m7wb/A+81vpJb664PN6WsE3k/3eibj0EX9UmqG9+8h9F66cMYCqOv2jwFv0uTo3KKq1Rsk/zT9a5syJJ/qDLlw1yXL3OURuDYE74ju1crkN9AA7CycOEpDw9OydaqM2g1/acWP3ZmLDLo5zF9pKig2gC1BbJtty4lSiebqhYf1hECVREC1DBCSWC97YNw51CR/qb9qV+Czw=" Oct 14 03:01:55 bcik go-librespot[26225]: time="2026-10-14T03:01:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:55 bcik go-librespot[26225]: time="2026-10-14T03:01:55+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:55 bcik go-librespot[26225]: time="2026-10-14T03:01:55+02:00" level=debug msg="completed challenge" Oct 14 03:01:55 bcik go-librespot[26225]: time="2026-10-14T03:01:55+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:55 bcik go-librespot[26225]: time="2026-10-14T03:01:55+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:55 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:55 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:56 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:56 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:01:58 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4298. Oct 14 03:01:58 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:58 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:01:58 bcik go-librespot[26234]: go-librespot daemon starting... Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=debug msg="app state loaded" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+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 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+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 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+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 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=info msg="zeroconf server listening on port 44973" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=debug msg="obtained new client token: AAEb3dgGuI5JVZfzNZFYoBo5TV/b8/T2K0I6mmO0JAbatgzQPZA/OZ/s8BGBNJSoZACS1ZJq8AHYMjaG7qdQrlyBQNSeKIFMn2o0KFZ9ozfG9afCge7BeGq0xkKCO8SoL7hrIDIAKBy024wBG2w3S8UWvYqDuY0lzWw2O/wWZKRJNfbgxa+n3cNi3uvIGFwZ/CQmCUyOxf60aJ1U75JS56uZ+aJrhEbbKAQX8dOB7LVvsZzCW8IH9P4=" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=debug msg="completed keyexchange" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=debug msg="completed challenge" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:01:58 bcik go-librespot[26235]: time="2026-10-14T03:01:58+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:01:58 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:01:58 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:01:59 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:01:59 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:01 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4299. Oct 14 03:02:01 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:01 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:01 bcik go-librespot[26259]: go-librespot daemon starting... Oct 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+02:00" level=debug msg="app state loaded" Oct 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+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 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+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 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+02:00" level=info msg="zeroconf server listening on port 45275" Oct 14 03:02:01 bcik go-librespot[26260]: time="2026-10-14T03:02:01+02:00" level=debug msg="obtained new client token: AAGBIiM+dnhjtpjm7CNIuoCTTfxaas10qr2crD3S5dYYIQNKfE2EjeJL/YXeaF43T2MZcD0X06y1IzEShCgLyafE7RWKerP+vanS3knLhkEy1SkInsQmQN0bHw/pa3LhUQDc8mPzIbFcDh8GD+0N55PVWxgrmSOi6RnYEv+TDbysrTHnaQKp+9uTHT9vFK4KVpf7j1DAKWEgAQ+4pxrDXMZklF5j8HNZ63zGat752VuW5MxZjOc/Ofw=" Oct 14 03:02:02 bcik go-librespot[26260]: time="2026-10-14T03:02:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:02 bcik go-librespot[26260]: time="2026-10-14T03:02:02+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:02 bcik go-librespot[26260]: time="2026-10-14T03:02:02+02:00" level=debug msg="completed challenge" Oct 14 03:02:02 bcik go-librespot[26260]: time="2026-10-14T03:02:02+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:02 bcik go-librespot[26260]: time="2026-10-14T03:02:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:02 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:02 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:02 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:02 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:05 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4300. Oct 14 03:02:05 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:05 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:05 bcik go-librespot[26269]: go-librespot daemon starting... Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=debug msg="app state loaded" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+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 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+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 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+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 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=info msg="zeroconf server listening on port 34047" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=debug msg="obtained new client token: AAFuKLVnxkEXzHL9GfvVas2uARmcvSDeRNmhkDI58gXt+FLpEK26tb70uRjBfsjAovCo2WKU2NuWRW0cgd0BoUUN38c5eoUK/AHhBZOPF/3Bn8d44aiUJW0c59HTUU+CZbzuTNwWxzXQJPD8I7EFtAQHLRXFgSFbdKdODGuKVqhg7WPFm4tbsXpeNp7iMr7wQgUQJ21I65t6oOwJfYwRHKaJwzpOI8mWKfnNufQcsA0pdui6/BHH5Ak=" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=debug msg="completed challenge" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:05 bcik go-librespot[26270]: time="2026-10-14T03:02:05+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:05 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:05 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:05 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:05 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:08 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:08 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:08 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4301. Oct 14 03:02:08 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:08 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:08 bcik go-librespot[26279]: go-librespot daemon starting... Oct 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02:08+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02:08+02:00" level=debug msg="app state loaded" Oct 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02:08+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02: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 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02: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 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02: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 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02:08+02:00" level=info msg="zeroconf server listening on port 42029" Oct 14 03:02:08 bcik go-librespot[26280]: time="2026-10-14T03:02:08+02:00" level=debug msg="obtained new client token: AAGrj/wJAZfKYF+7mMeMGUKpg1CnxMkxIunhPvEH7WoRx4+dBeLGN88M9HYj/oxuBQXpO07WxQdBPslAFqg5rJeISPY3d+ev7NheuFwC7KJC6+g/6EM1pTORjBfKxdnQzYuu1v3bJvvvzZBPlert5ViW3zB5HGbJChH39ZmiUE9KDrDWq3zxYSXmZ4m+jLM5sZW4ReSdHC7O5v3Imh5SSH1Sd7c5UbjFVxpIKPs8JfB0nQUxiwxmt1s=" Oct 14 03:02:09 bcik go-librespot[26280]: time="2026-10-14T03:02:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:09 bcik go-librespot[26280]: time="2026-10-14T03:02:09+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:09 bcik go-librespot[26280]: time="2026-10-14T03:02:09+02:00" level=debug msg="completed challenge" Oct 14 03:02:09 bcik go-librespot[26280]: time="2026-10-14T03:02:09+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:09 bcik go-librespot[26280]: time="2026-10-14T03:02:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:09 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:09 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:11 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:11 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:12 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4302. Oct 14 03:02:12 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:12 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:12 bcik go-librespot[26304]: go-librespot daemon starting... Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=debug msg="app state loaded" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+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 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+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 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+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 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=info msg="zeroconf server listening on port 41957" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=debug msg="obtained new client token: AAGAR/zMgLRJ836fKu/n0+QejrM4pJrm5ZIksPlGw4wuCk8QTZiT4XsE8TZ3CogQFlVwI0xZZYQNGn3QWP3b5+RjOuOxeca6CRo1HYGu3j58w6FDYXlNDZwQO5cOdJtdvifJKV7R+ANCpoMunXje8XM/O9PeRYt57BKzSqTTUg4kUbEuOek3ovWQTd7N3cIaxriXiDK1EriRIpM/Sp2yNQdtkCfvOi29lpAbaPah/k4AYKgtiNLOvfc=" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=debug msg="completed challenge" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:12 bcik go-librespot[26305]: time="2026-10-14T03:02:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:12 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:12 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:14 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:14 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:15 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4303. Oct 14 03:02:15 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:15 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:15 bcik go-librespot[26314]: go-librespot daemon starting... Oct 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+02:00" level=debug msg="app state loaded" Oct 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+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 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+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 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+02:00" level=info msg="zeroconf server listening on port 45077" Oct 14 03:02:15 bcik go-librespot[26315]: time="2026-10-14T03:02:15+02:00" level=debug msg="obtained new client token: AAFPFjnY01D1BIxv0KaKYZKct8BS7UxgvyQEY2522tZFHo1FHfzMcBby5y0+s79R6XLMAESUEeCXM+85q2XZqc0Z4J4tBLu9cTt64oC19FfSSfi8PipjInyBszjC8nowvLLwNRQei38Ij5fDTCU4xWKpsoZpiUM6ssk3by3p8mDkBoJ09F3S47J+dzwPODRwhGfdExiT9EpOaXrbykQClux3LdEwtYcQ4LhVM4o921e0d+1orVfoEAo=" Oct 14 03:02:16 bcik go-librespot[26315]: time="2026-10-14T03:02:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:16 bcik go-librespot[26315]: time="2026-10-14T03:02:16+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:16 bcik go-librespot[26315]: time="2026-10-14T03:02:16+02:00" level=debug msg="completed challenge" Oct 14 03:02:16 bcik go-librespot[26315]: time="2026-10-14T03:02:16+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:16 bcik go-librespot[26315]: time="2026-10-14T03:02:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:16 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:16 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:17 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:17 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:19 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4304. Oct 14 03:02:19 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:19 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:19 bcik go-librespot[26324]: go-librespot daemon starting... Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=debug msg="app state loaded" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+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 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+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 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=info msg="zeroconf server listening on port 44255" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=debug msg="obtained new client token: AAEIo5kWSj86zZTj8c0NwOSHuBIjVMfWq4OgR9lInJKASzX8UGkZlIbyzMuDQOvYs+GIiGm4kyioGAwssi7jVoySIuD/4v658lTBk+rqUCXW+jtD4bAy6OqrtL0G46Ital+Tuv75Lt6L/FpzD6Jm7HbNvfWhGhNuPIM3BA1ZFbqJRqnB8BJad+YJcdl77RzUI2piMauxswLcccwbiTjOrRE6G2qDESkKLv7gDoo4/wopSuxC+YqWZDc=" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=debug msg="completed challenge" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02:19+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:19 bcik go-librespot[26325]: time="2026-10-14T03:02: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 14 03:02:19 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:19 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:20 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:20 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:22 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4305. Oct 14 03:02:22 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:22 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:22 bcik go-librespot[26348]: go-librespot daemon starting... Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+02:00" level=debug msg="app state loaded" Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02: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-gew1.spotify.com:80]" Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+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 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+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 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+02:00" level=info msg="zeroconf server listening on port 41811" Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+02:00" level=debug msg="obtained new client token: AAEMGHt3HoLzTqmkzEVc3WB3SpUKqpkcQm0s5UbYEk2rQSydFF8Lkn+WQ19Y98nRc1F1ABWQPJep8xURapRBA57nj4zh0c8YMT0K7IVPd+aRwPfdEAvXuJ38uv1e/MTcX5vg4xL0BZqNWj6kbKqF50QtCITf0/vPDKUDTbGn3fC+kM2NtoBlSrZu6GasiP2Yz0rRbdh1DEHuZ3YUGhU5AK4GIufMBVSINFYOVhVRi/7tOmjeDIR1XqQ=" Oct 14 03:02:22 bcik go-librespot[26349]: time="2026-10-14T03:02:22+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:23 bcik go-librespot[26349]: time="2026-10-14T03:02:23+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:23 bcik go-librespot[26349]: time="2026-10-14T03:02:23+02:00" level=debug msg="completed challenge" Oct 14 03:02:23 bcik go-librespot[26349]: time="2026-10-14T03:02:23+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:23 bcik go-librespot[26349]: time="2026-10-14T03:02:23+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:23 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:23 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:23 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:23 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:26 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4306. Oct 14 03:02:26 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:26 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:26 bcik go-librespot[26359]: go-librespot daemon starting... Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=debug msg="app state loaded" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+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 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+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 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=info msg="zeroconf server listening on port 40277" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=debug msg="obtained new client token: AAHaMfQh2RUJ28joWA2JqheNVoBACnzlAygcRxDnqNs3SE1Svfl6N0I7EQkY7dX6nXIZs0f4VbSl5hiYd5D+PL3R9DR1vn781IMRwfU7H6f02hVMJzgnoLUTWK2oBlU8CYMuobRQwvx5MpytTSFD1xZXDjGqh8pKLHxvophA6IjEfaXDfoKr2oFtdI0Cq+Sa04SrZS5P34pT4eF9vQk/dfnIwNm05285BMTZE/sAaQYpsz0mLWXq7cM=" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+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 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=debug msg="completed challenge" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02:26+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:26 bcik go-librespot[26360]: time="2026-10-14T03:02: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 14 03:02:26 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:26 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:26 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:26 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:29 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:29 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:29 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4307. Oct 14 03:02:29 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:29 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:29 bcik go-librespot[26369]: go-librespot daemon starting... Oct 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+02:00" level=debug msg="app state loaded" Oct 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+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 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+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 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+02:00" level=info msg="zeroconf server listening on port 39219" Oct 14 03:02:29 bcik go-librespot[26370]: time="2026-10-14T03:02:29+02:00" level=debug msg="obtained new client token: AAFdRxlHPqXag2TSnobqzAWja6430C8pdTJn7wGwVMHxO6ZOYSritxSPmys0+GTFYCAiatI2AJB94zCEo6ebvcAS36qwlnArO0OwGFTQwPPg5v5kzok0heHaujFJW5NU8jVs6kMVV4bwTpBIWsxzvI7moBlPwD/ncni/hmGPYjBBNJxb8ZdzQovhDkokA75aPSBGNivMWQpRl0l1wwnFF38LAQFPuV6UraJYF77BKN1io4uw53MGAC0=" Oct 14 03:02:30 bcik go-librespot[26370]: time="2026-10-14T03:02:30+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:30 bcik go-librespot[26370]: time="2026-10-14T03:02:30+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:30 bcik go-librespot[26370]: time="2026-10-14T03:02:30+02:00" level=debug msg="completed challenge" Oct 14 03:02:30 bcik go-librespot[26370]: time="2026-10-14T03:02:30+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:30 bcik go-librespot[26370]: time="2026-10-14T03:02:30+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:30 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:30 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:32 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:32 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:33 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4308. Oct 14 03:02:33 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:33 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:33 bcik go-librespot[26393]: go-librespot daemon starting... Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=debug msg="app state loaded" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+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 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+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 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=info msg="zeroconf server listening on port 41621" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=debug msg="obtained new client token: AAEzd391kFGgTwa1+51gEVSwcapiEc0YQyUfGdpRKIqRkS88g75R9S3fiJBYFAGfO/qx8lyTdZteiMQHutZ9pmVU/f1TdFzyaQwJ7UjAa6w+griSXkwF82bgSqCGDQ/xR5SZUqRA5fghg1KPmRBYVyOX8tdnXlYnBWJxz+DVA/ELtFoAo9Yn8bd/fihEZTwwtcyulSZ9+QTKQhspyCx7a/bp9MAoEPcYG74PfQb1PGlHPgigK0jlDds=" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=debug msg="completed challenge" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02:33+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:33 bcik go-librespot[26394]: time="2026-10-14T03:02: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 14 03:02:33 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:33 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:35 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:35 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:36 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4309. Oct 14 03:02:36 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:36 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:36 bcik go-librespot[26403]: go-librespot daemon starting... Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+02:00" level=debug msg="app state loaded" Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+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 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+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 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+02:00" level=info msg="zeroconf server listening on port 35363" Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+02:00" level=debug msg="obtained new client token: AAEfnwvsZq1BNC+le1Wbrn98zW6kuJgz5pvfO8o2lhYNX7JYehBpa03ii3kgDU64vfipMpH52WzrvrW9/FzZLxA3X3ZplzfC9dXyUFCYZYzX/5pOyp/5Pe0lXx3BssX59hunYTSRMtucTWXCP9RTWYM7o+B76ktdQFyKWkpZocFzFwWhxoTeIMUaqINgzF4dlAIsfeMyjkFGGdU975MI7cSI+Z442mp3x5Rw1uoZ11JK/hhAOxXwkT8=" Oct 14 03:02:36 bcik go-librespot[26404]: time="2026-10-14T03:02:36+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:37 bcik go-librespot[26404]: time="2026-10-14T03:02:37+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:37 bcik go-librespot[26404]: time="2026-10-14T03:02:37+02:00" level=debug msg="completed challenge" Oct 14 03:02:37 bcik go-librespot[26404]: time="2026-10-14T03:02:37+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:37 bcik go-librespot[26404]: time="2026-10-14T03:02:37+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:37 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:37 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:38 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:38 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:40 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4310. Oct 14 03:02:40 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:40 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:40 bcik go-librespot[26415]: go-librespot daemon starting... Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=debug msg="app state loaded" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02: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 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02: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 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02: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 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=info msg="zeroconf server listening on port 38503" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=debug msg="obtained new client token: AAHTKXKHPKKhP/LAywioEPRPOg7dTooxcHuU2tCKRIxtr2pfmsSjNZnw6LFUbKjvDAB9UBWwvKWSc8cYtJ308GShfFLMpbQy1iMW6rPbnR2K4EBuM23Fue/I38mZyWk1JHr1Hd9FcqhnD9Ov2h1RH+yaVWQGpkT0y6mXSktIrtatrVSHDt5rE7N97v07eI3saVMTbxF3ypCmQeJTN92tqo+BE76HT3JN33isuUfJn4PSaxYt7ZdL8sA=" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+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 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=debug msg="completed challenge" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02:40+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:40 bcik go-librespot[26416]: time="2026-10-14T03:02: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 14 03:02:40 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:40 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:41 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:41 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:44 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4311. Oct 14 03:02:44 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:44 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:44 bcik go-librespot[26439]: go-librespot daemon starting... Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=debug msg="app state loaded" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+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 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+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 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+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 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=info msg="zeroconf server listening on port 41257" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=debug msg="obtained new client token: AAHg5bbc1Wm2amA737lh4DVojEAGnz65tZNECRRCFTkbBzqx3/p2Z90XD85Sb40ea9SuhPWEWo5+BL/iPzdNFRrnOekEvEz1xr+9WuTtobT3rOy92ElQdcKgDESTrJ5oMyca0uKh2FP3P6VxWnBDabXlBPrPzglgWHncJAh7ZaBBN7hLsSxSC86tYJYU+kSK8SaTL4wVIdMRwtQ6qxb3GRK2gXku6XX7rcHrJXZW8P3eTmc+G7c3" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=debug msg="completed challenge" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:44 bcik go-librespot[26440]: time="2026-10-14T03:02:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 14 03:02:44 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:44 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:44 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:44 bcik volumio[25704]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:47 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4312. Oct 14 03:02:47 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:47 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 14 03:02:47 bcik go-librespot[26450]: go-librespot daemon starting... Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=info msg="running go-librespot 0.6.2" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=debug msg="app state loaded" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+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 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+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 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=info msg="zeroconf server listening on port 39701" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=debug msg="obtained new client token: AAF4izzy/JD+aImGf+r7YKf0d6mrk26USOZry8k24c1VuEMm1HnNpD/+NRrmNVQs5U27amZqpHIToA1VpsecvyerC89hCGcHdKBHqzjxBGR3l6iPkDm+K/cgfUewKQJV6q7+59zRfwvfGXm27H58ZCGVELNh/7weBOanWRuS/Z0CFfLfr6m21A5sMhIHbJFG5glPIl3wrsizcx81U1p+4RD86/qSv2Qi8kRjgXhYIzy+dBKsxNkvYd4=" Oct 14 03:02:47 bcik volumio[25704]: info: Initializing connection to go-librespot Websocket Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=debug msg="new websocket client" Oct 14 03:02:47 bcik volumio[25704]: info: Connection to go-librespot Websocket established Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=debug msg="completed keyexchange" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=debug msg="completed challenge" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02:47+02:00" level=info msg="authenticated AP" username="la***pl" Oct 14 03:02:47 bcik go-librespot[26451]: time="2026-10-14T03:02: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 14 03:02:47 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 14 03:02:47 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 14 03:02:47 bcik volumio[25704]: info: Connection to go-librespot Websocket closed Oct 14 03:02:50 bcik volumio[25704]: info: Getting Spotify volume Oct 14 03:02:50 bcik volumio[25704]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 03:02:50 bcik volumio[25704]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 14 03:02:50 bcik volumio[25704]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 14 03:02:50 bcik volumio[25704]: errno: -111, Oct 14 03:02:50 bcik volumio[25704]: code: 'ECONNREFUSED', Oct 14 03:02:50 bcik volumio[25704]: syscall: 'connect', Oct 14 03:02:50 bcik volumio[25704]: address: '127.0.0.1', Oct 14 03:02:50 bcik volumio[25704]: port: 9879, Oct 14 03:02:50 bcik volumio[25704]: response: undefined Oct 14 03:02:50 bcik volumio[25704]: } Oct 14 03:02:50 bcik volumio[25704]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 03:02:50 bcik sudo[26474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 03:01' Oct 14 03:02:50 bcik sudo[26474]: 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"