Oct 15 01:00:00 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 01:00:00 bcik volumio[20581]: 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 15 01:00:01 bcik volumio[20581]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:01 bcik volumio[20581]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:01 bcik volumio[20581]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:01 bcik volumio[20581]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:01 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 15 01:00:01 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:01 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:01 bcik go-librespot[20966]: go-librespot daemon starting... Oct 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+02:00" level=debug msg="app state loaded" Oct 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+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 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+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 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+02:00" level=info msg="zeroconf server listening on port 45479" Oct 15 01:00:01 bcik go-librespot[20967]: time="2026-10-15T01:00:01+02:00" level=debug msg="obtained new client token: AAG/ZXmkDmsU6edGBsuNPLLoenX0l8qCgDD042GiaLrmiQN7On8QVflwxHQkJkQ7ki6OnD1od1fdDNyiBQJ/VGw5hU7POU/t/2m10C29MnvIuA3WgX07J6pBvT2YMMR0Za+dQ+8iUXFPB/isDPYZtilFcb941QXuzhm32pJVjsoE1IFsIlriL/1ZKQxS8bE/k+ji9zFOuPA+EOWqSaup2hJuOjaGMWplzdXxLsQQYtd+wbjuuHntBBw=" Oct 15 01:00:04 bcik volumio[20581]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:04 bcik go-librespot[20967]: time="2026-10-15T01:00:04+02:00" level=debug msg="new websocket client" Oct 15 01:00:04 bcik volumio[20581]: info: Connection to go-librespot Websocket established Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::volumioGetBrowseSources Oct 15 01:00:05 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:06 bcik volumio[20581]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 15 01:00:06 bcik volumio[20581]: info: Completed starting MyVolumio Plugin Oct 15 01:00:06 bcik volumio[20581]: [Metrics] CommandRouter: 30s 718.02ms Oct 15 01:00:06 bcik volumio[20581]: info: CoreCommandRouter::volumiosetStartupVolume Oct 15 01:00:06 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:06 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:06 bcik volumio[20581]: info: CoreCommandRouter::Close All Modals sent Oct 15 01:00:06 bcik volumio[20581]: info: CoreCommandRouter::Close All Modals sent Oct 15 01:00:07 bcik go-librespot[20967]: time="2026-10-15T01:00:07+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 15 01:00:07 bcik go-librespot[20967]: time="2026-10-15T01:00:07+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:07 bcik go-librespot[20967]: time="2026-10-15T01:00:07+02:00" level=debug msg="completed challenge" Oct 15 01:00:07 bcik volumio[20581]: info: Getting Spotify volume Oct 15 01:00:07 bcik volumio[20581]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:07 bcik volumio[20581]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:07 bcik volumio[20581]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Oct 15 01:00:07 bcik volumio[20581]: SPOTIFY: SPOTIFY VOLUME undefined Oct 15 01:00:07 bcik volumio[20581]: SPOTIFY: VOLUMIO VOLUME 41 Oct 15 01:00:07 bcik volumio[20581]: info: Aligning Spotify Volume to Volumio Volume Oct 15 01:00:07 bcik volumio[20581]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:07 bcik volumio[20581]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:07 bcik volumio[20581]: info: Setting Spotify Volume from Volumio: 41 Oct 15 01:00:07 bcik go-librespot[20967]: time="2026-10-15T01:00:07+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:07 bcik go-librespot[20967]: time="2026-10-15T01:00:07+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:07 bcik volumio[20581]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 01:00:07 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:07 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:07 bcik volumio[20581]: Error: socket hang up Oct 15 01:00:07 bcik volumio[20581]: at connResetException (node:internal/errors:720:14) Oct 15 01:00:07 bcik volumio[20581]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 15 01:00:07 bcik volumio[20581]: at Socket.emit (node:events:526:35) Oct 15 01:00:07 bcik volumio[20581]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 15 01:00:07 bcik volumio[20581]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 15 01:00:07 bcik volumio[20581]: code: 'ECONNRESET', Oct 15 01:00:07 bcik volumio[20581]: response: undefined Oct 15 01:00:07 bcik volumio[20581]: } Oct 15 01:00:07 bcik volumio[20581]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 01:00:07 bcik sudo[21009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 00:59' Oct 15 01:00:07 bcik sudo[21009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:07 bcik sudo[21009]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:08 bcik volumio5-onboarding[9231]: time=2026-10-15T01:00:08.120+02:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 15 01:00:08 bcik volumio5-onboarding[9231]: time=2026-10-15T01:00:08.121+02:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Oct 15 01:00:08 bcik volumio-remote-updater[9031]: [2026-10-15 01:00:08] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 15 01:00:08 bcik volumio-remote-updater[9031]: [2026-10-15 01:00:08] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 15 01:00:08 bcik systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:08 bcik systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 15 01:00:08 bcik systemd[1]: volumio.service: Consumed 6.639s CPU time. Oct 15 01:00:08 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 15 01:00:08 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 15 01:00:08 bcik systemd[1]: volumio.service: Scheduled restart job, restart counter is at 25421. Oct 15 01:00:08 bcik systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 15 01:00:08 bcik systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 15 01:00:08 bcik systemd[1]: volumio.service: Consumed 6.639s CPU time. Oct 15 01:00:08 bcik systemd[1]: Started volumio.service - Volumio Backend Module. Oct 15 01:00:08 bcik systemd[1]: dynamicswap.service: Deactivated successfully. Oct 15 01:00:08 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:08 bcik volumio[21024]: info: ----- Volumio3 ---- Oct 15 01:00:08 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:08 bcik volumio[21024]: info: ----- System startup ---- Oct 15 01:00:08 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:08 bcik volumio[21024]: info: MYVOLUMIO Environment detected Oct 15 01:00:08 bcik volumio[21024]: info: Plugin folders cleanup Oct 15 01:00:08 bcik volumio[21024]: info: Scanning into folder /volumio/app/plugins/ Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category audio_interface Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category miscellanea Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category music_service Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category plugins.json Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category system_controller Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category user_interface Oct 15 01:00:08 bcik volumio[21024]: info: Scanning into folder /data/plugins/ Oct 15 01:00:08 bcik volumio[21024]: info: Scanning category music_service Oct 15 01:00:08 bcik volumio[21024]: info: Plugin folders cleanup completed Oct 15 01:00:08 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:08 bcik volumio[21024]: info: ----- Core plugins startup ---- Oct 15 01:00:08 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:08 bcik volumio[21024]: info: Loading plugins from folder /volumio/app/plugins/ Oct 15 01:00:08 bcik volumio[21024]: info: Adding plugin upnp to MyMusic Plugins Oct 15 01:00:08 bcik volumio[21024]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 15 01:00:08 bcik volumio[21024]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 15 01:00:08 bcik volumio[21024]: info: Loading plugins from folder /data/plugins/ Oct 15 01:00:08 bcik volumio[21024]: info: Loading plugin "system"... Oct 15 01:00:08 bcik volumio[21024]: info: Loading plugin "appearance"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "network"... Oct 15 01:00:09 bcik volumio[21024]: info: Refreshing Cached IP Addresses Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "services"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "volumio5onboarding"... Oct 15 01:00:09 bcik sudo[21052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 01:00:09 bcik sudo[21054]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 01:00:09 bcik sudo[21052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:09 bcik sudo[21054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:09 bcik sudo[21054]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "alsa_controller"... Oct 15 01:00:09 bcik sudo[21052]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "wizard"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "networkfs"... Oct 15 01:00:09 bcik volumio[21024]: info: Starting Udev Watcher for removable devices Oct 15 01:00:09 bcik volumio[21024]: info: Ignoring mount for partition: boot Oct 15 01:00:09 bcik volumio[21024]: info: Ignoring mount for partition: volumio Oct 15 01:00:09 bcik volumio[21024]: info: Ignoring mount for partition: volumio_data Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "volumio_command_line_client"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "upnp"... Oct 15 01:00:09 bcik volumio[21024]: info: [1792018809109] Starting Upmpd Daemon Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "my_music"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "mpd"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "upnp_browser"... Oct 15 01:00:09 bcik volumio[21024]: info: Starting UPNP Browser Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "alarm-clock"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "airplay_emulation"... Oct 15 01:00:09 bcik volumio[21024]: info: Starting Shairport Sync Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "last_100"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "webradio"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "i2s_dacs"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "volumiodiscovery"... Oct 15 01:00:09 bcik volumio[21024]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 01:00:09 bcik volumio[21024]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 01:00:09 bcik volumio[21024]: *** WARNING *** For more information see Oct 15 01:00:09 bcik volumio[21024]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 01:00:09 bcik volumio[21024]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 01:00:09 bcik volumio[21024]: *** WARNING *** For more information see Oct 15 01:00:09 bcik node[21024]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 15 01:00:09 bcik node[21024]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 01:00:09 bcik node[21024]: *** WARNING *** For more information see Oct 15 01:00:09 bcik node[21024]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 15 01:00:09 bcik node[21024]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 15 01:00:09 bcik node[21024]: *** WARNING *** For more information see Oct 15 01:00:09 bcik volumio[21024]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 15 01:00:09 bcik volumio[21024]: info: Discovery: Started advertising with name: Bącik Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "spop"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "outputs"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "albumart"... Oct 15 01:00:09 bcik volumio[21024]: info: Plugin example_plugin is not enabled Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "inputs"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "updater_comm"... Oct 15 01:00:09 bcik volumio[21024]: info: Plugin mpdemulation is not enabled Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "rest_api"... Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "websocket"... Oct 15 01:00:09 bcik volumio[21024]: info: Starting Socket.io Server version 1.7.4 Oct 15 01:00:09 bcik volumio[21024]: info: Loading plugin "radio_browser"... Oct 15 01:00:09 bcik volumio[21084]: Forking 3 albumart workers Oct 15 01:00:09 bcik volumio[21024]: info: Loading i18n strings for locale pl Oct 15 01:00:09 bcik volumio[21024]: Updating browse sources language Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::initPlayerControls Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: Express server listening on port 3000 Oct 15 01:00:09 bcik volumio[21024]: [Metrics] WebUI: 1s 278.57ms Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::resetVolumioState Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::getcurrentVolume Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 01:00:09 bcik volumio[21024]: info: Volumio Network Manager: Network status updated: 1 Oct 15 01:00:09 bcik volumio[21024]: 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 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:09 bcik volumio[21024]: info: Reloading queue from file Oct 15 01:00:09 bcik volumio[21024]: 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 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::setRepeat false single undefined Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::pushState Oct 15 01:00:09 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::volumioPushState Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::setRandom false Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::pushState Oct 15 01:00:09 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::volumioPushState Oct 15 01:00:09 bcik volumio[21024]: info: VolumeController:: Volume=41 Mute =false Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::pushState Oct 15 01:00:09 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::volumioPushState Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::updateTrackBlock Oct 15 01:00:09 bcik volumio[21024]: info: CorePlayQueue::getTrackBlock Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 01:00:09 bcik volumio[21024]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 15 01:00:09 bcik volumio[21024]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04171 Oct 15 01:00:09 bcik volumio[21024]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 15 01:00:09 bcik volumio[21024]: 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 15 01:00:09 bcik volumio[21024]: info: Setting Device type: Raspberry PI Oct 15 01:00:09 bcik volumio[21024]: info: Completed loading Core Plugins Oct 15 01:00:09 bcik volumio[21024]: info: Preparing to generate the ALSA configuration file Oct 15 01:00:09 bcik volumio[21024]: 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 15 01:00:09 bcik volumio[21024]: 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 15 01:00:09 bcik volumio[21024]: info: VolumeController:: Volume=41 Mute =false Oct 15 01:00:09 bcik volumio[21024]: info: CoreStateMachine::pushState Oct 15 01:00:09 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:09 bcik volumio[21024]: info: CoreCommandRouter::volumioPushState Oct 15 01:00:09 bcik volumio[21024]: 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 15 01:00:09 bcik volumio[21024]: info: Asound.conf file unchanged, so no further update is needed Oct 15 01:00:09 bcik volumio[21024]: info: Output device has changed, restarting MPD Oct 15 01:00:10 bcik volumio[21024]: info: Output device has changed, restarting Shairport Sync Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:10 bcik sudo[21139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 01:00:10 bcik sudo[21139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik sudo[21139]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik sudo[21140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 01:00:10 bcik sudo[21140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik volumio[21024]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 01:00:10 bcik volumio[21024]: info: ___________ START PLUGINS ___________ Oct 15 01:00:10 bcik volumio[21024]: info: ControllerMpd::onStart: Initializing MPD Oct 15 01:00:10 bcik volumio[21024]: info: Creating MPD Configuration file Oct 15 01:00:10 bcik systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 01:00:10 bcik volumio[21024]: info: [1792018810041] CoreMusicLibrary::Adding element Serwery Mediów Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 01:00:10 bcik sudo[21148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 15 01:00:10 bcik sudo[21148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik volumio[21024]: info: UPNP Browser: Client initialized successfully Oct 15 01:00:10 bcik sudo[21152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 01:00:10 bcik sudo[21152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik sudo[21150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 01:00:10 bcik sudo[21150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 15 01:00:10 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 01:00:10 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 15 01:00:10 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 01:00:10 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 01:00:10 bcik sudo[21150]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:10 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 01:00:10 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 01:00:10 bcik sudo[21148]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik volumio[21024]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 01:00:10 bcik systemd[1]: mpd.service: Deactivated successfully. Oct 15 01:00:10 bcik systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 01:00:10 bcik volumio[21024]: info: [1792018810112] CoreMusicLibrary::Adding element Last_100 Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 01:00:10 bcik systemd[1]: mpd.socket: Deactivated successfully. Oct 15 01:00:10 bcik systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 01:00:10 bcik systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 01:00:10 bcik systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 01:00:10 bcik volumio[21094]: Starting albumart workers Oct 15 01:00:10 bcik systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 01:00:10 bcik volumio[21024]: info: [1792018810118] CoreMusicLibrary::Adding element Webradio Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:10 bcik volumio[21024]: info: Initializing BBC Radios Oct 15 01:00:10 bcik sudo[21167]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 01:00:10 bcik sudo[21167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 01:00:10 bcik sudo[21167]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik volumio[21095]: Starting albumart workers Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: Creating Spotify config file Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21096]: Starting albumart workers Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 15 01:00:10 bcik volumio[21024]: info: [1792018810317] CoreMusicLibrary::Adding element Radio Browser Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 01:00:10 bcik volumio[21024]: Cannot find translation for source Radio Browser Oct 15 01:00:10 bcik volumio[21024]: info: Volumio Calling Home Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: info: MPD Permissions set Oct 15 01:00:10 bcik volumio[21024]: info: MPD Permissions set Oct 15 01:00:10 bcik volumio[21024]: info: Spotify config file written Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik sudo[21194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 15 01:00:10 bcik sudo[21194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:10 bcik volumio[21024]: info: No need to fix Spotify hosts Oct 15 01:00:10 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:10 bcik go-librespot[21209]: go-librespot daemon starting... Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik sudo[21194]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=debug msg="app state loaded" Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:10 bcik volumio[21024]: info: Volumio called home Oct 15 01:00:10 bcik volumio[21024]: info: Discovery: adding f1ed5fb6-e21d-47c8-819b-29842600355e Oct 15 01:00:10 bcik volumio[21024]: info: Discovery: Found device Bącik Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:10 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: info: Starting Shairport Sync Oct 15 01:00:10 bcik volumio[21024]: info: Starting Shairport Sync Oct 15 01:00:10 bcik volumio[21024]: info: Starting Shairport Sync Oct 15 01:00:10 bcik sudo[21228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 01:00:10 bcik sudo[21228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik volumio[21024]: info: Discovery: this is already registered, f1ed5fb6-e21d-47c8-819b-29842600355e Oct 15 01:00:10 bcik volumio[21024]: info: Discovery: Found device Bącik Oct 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:10 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:10 bcik sudo[21230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 01:00:10 bcik sudo[21230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 01:00:10 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 01:00:10 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 01:00:10 bcik sudo[21233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 01:00:10 bcik systemd[1]: shairport-sync.service: Consumed 1.547s CPU time. Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+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 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+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 15 01:00:10 bcik sudo[21233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=info msg="zeroconf server listening on port 35083" Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 01:00:10 bcik volumio[21024]: info: An error occurred while refreshing Spotify Token Error: Bad Request Oct 15 01:00:10 bcik sudo[21228]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:10 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:10 bcik volumio[21024]: info: Shairport-Sync Started Oct 15 01:00:10 bcik volumio[21024]: Error adding Membership: Error: addMembership EINVAL Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 01:00:10 bcik systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=debug msg="obtained new client token: AAF/T5JQP0b5JaD9ccsRG2NX05MbUNILFY57fAYM6KZPE/uJD3+hGzQbOIRrsZ5+yPG1fDEhuttj7VKLQZ9Q+mUxBMoLG2xvFpKQkW67Nwy1JeiJUWugkfig8cmeQMtX7ND/t6jXsGUdtk8j417gbBuT0YNYO5IBb1Ph6ErppJsK8iaoq0B1CXz1TAuaXGLkUaXREwPr8OfzZekOM3dXGECH+GfaKQVdiOg4f/iowFKrcr8XjASMlMA=" Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik sudo[21233]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik sudo[21230]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: info: Shairport-Sync Started Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: info: Shairport-Sync Started Oct 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik volumio[21024]: 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 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=debug msg="completed challenge" Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:10 bcik mpd[21182]: 2026-10-15T01:00:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 01:00:10 bcik systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 01:00:10 bcik sudo[21140]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik sudo[21152]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:10 bcik volumio[21024]: info: Completed starting Core Plugins Oct 15 01:00:10 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:10 bcik volumio[21024]: info: ----- MyVolumio plugins startup ---- Oct 15 01:00:10 bcik volumio[21024]: info: ------------------------------------------- Oct 15 01:00:10 bcik volumio[21024]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 15 01:00:10 bcik volumio[21024]: error: MPD error: The expression evaluated to a falsy value: Oct 15 01:00:10 bcik volumio[21024]: assert.ok(self.idling) Oct 15 01:00:10 bcik volumio[21024]: error: The expression evaluated to a falsy value: Oct 15 01:00:10 bcik volumio[21024]: assert.ok(self.idling) Oct 15 01:00:10 bcik volumio[21024]: info: MPD running with PID21182 Oct 15 01:00:10 bcik volumio[21024]: ,establishing connection Oct 15 01:00:10 bcik volumio[21024]: error: updateQueue error: null Oct 15 01:00:10 bcik volumio[21024]: error: updateQueue error: null Oct 15 01:00:10 bcik go-librespot[21220]: time="2026-10-15T01:00:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:10 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:10 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:13 bcik volumio-remote-updater[9031]: [2026-10-15 01:00:13] [connect] Successful connection Oct 15 01:00:13 bcik volumio-remote-updater[9031]: [2026-10-15 01:00:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1792018813 101 Oct 15 01:00:13 bcik volumio[21024]: 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 15 01:00:13 bcik volumio[21024]: info: go-librespot daemon successfully initialized Oct 15 01:00:14 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 15 01:00:14 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:14 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:14 bcik go-librespot[21271]: go-librespot daemon starting... Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=debug msg="app state loaded" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+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 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+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 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=info msg="zeroconf server listening on port 38005" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=debug msg="obtained new client token: AAG5M9KQBnE9aUN/TIlqfYY73Y+d8pxdw3jwvoXRjTPvgHZYBmrEY52NVZYQUKC80fpCJEGfGSsvbgeLabBnsTu3SFHxRMeL2YYhlOm6T2JLI7SyFNirnuGpj79WvyOTuKyv2YpkqHmEOmNN9+Ixtgh6FM1PJPZCXbJFYQiKrbOnVSG5oQCKwgHQpr3Xuz/yVGwCYNc+yfELhYjWxfxmXClKHuHwFzDEZc4YD709foeCAqQLbPFf" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+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 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=debug msg="completed challenge" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:14 bcik go-librespot[21272]: time="2026-10-15T01:00:14+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:14 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:14 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:15 bcik volumio[21024]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 15 01:00:16 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:16 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:17 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Oct 15 01:00:17 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:17 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:17 bcik go-librespot[21281]: go-librespot daemon starting... Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=debug msg="app state loaded" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+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 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+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 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+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 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=info msg="zeroconf server listening on port 41575" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=debug msg="obtained new client token: AAEQpWz3Y8/7DbD1ksHh1cy1TPvckD42bvNRs8HxrfZ2CkhE4xK1gJzehJM369Us8oHC6gfUff0XtAXTgj8/qOlcIG5lg8MXcVWCzr+ezitZc6XFqWgt/ZNYkZocbmwFqGr3dK17TaCUJ2uAzwZlrMA7PxfKGSfxb0nP7y6YkuusqMl6C6agHt7fvIvbONvxqkj68I7bbNhfyVSyLMHbFsDC3CWEP6p0UWoIS5SFusxb28p2Yjfhtp4=" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=debug msg="completed challenge" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:17 bcik go-librespot[21282]: time="2026-10-15T01:00:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:17 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:17 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin bluetooth to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin multiroom to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin metavolumio to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin cd_controller to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 15 01:00:18 bcik volumio[21024]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 15 01:00:19 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:19 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:19 bcik volumio[21024]: info: Starting MyVolumio Remote Streaming Endpoints Oct 15 01:00:19 bcik volumio[21024]: info: MyVolumio login type: Token Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 15 01:00:19 bcik volumio[21024]: info: Streaming services startup Oct 15 01:00:19 bcik volumio[21024]: info: Starting Streaming Daemon Oct 15 01:00:19 bcik sudo[21306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 15 01:00:19 bcik sudo[21306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:19 bcik volumio[21024]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 15 01:00:19 bcik sudo[21306]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:19 bcik sudo[21313]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 01:00:19 bcik sudo[21313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:19 bcik volumio[21024]: error: Cannot start Volumio Streaming Daemon Oct 15 01:00:19 bcik volumio[21024]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 15 01:00:19 bcik volumio[21024]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 15 01:00:19 bcik sudo[21313]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:19 bcik sudo[21315]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 01:00:19 bcik sudo[21315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:19 bcik sudo[21315]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:19 bcik sudo[21319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 15 01:00:19 bcik sudo[21319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:19 bcik sudo[21319]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:19 bcik volumio[21024]: info: Upmpdcli Daemon Started Oct 15 01:00:19 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:19 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:19 bcik volumio[21024]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 15 01:00:20 bcik volumio[21024]: info: MyVolumio token set successfully Oct 15 01:00:20 bcik volumio[21024]: info: MYVOLUMIO: Adding device Oct 15 01:00:20 bcik volumio[21024]: info: MYVOLUMIO: Evaluating Server Oct 15 01:00:20 bcik volumio[21024]: info: MyVolumio status changed Oct 15 01:00:20 bcik volumio[21024]: info: Streaming services startup Oct 15 01:00:20 bcik volumio[21024]: info: Starting Streaming Daemon Oct 15 01:00:20 bcik volumio[21024]: info: Removing browser output: myVolumio user plan is not superstar Oct 15 01:00:20 bcik volumio[21024]: info: Removing audio output: Oct 15 01:00:20 bcik volumio[21024]: info: Stoppping Tunnel 1 Oct 15 01:00:20 bcik sudo[21342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 15 01:00:20 bcik sudo[21342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:20 bcik sudo[21344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 15 01:00:20 bcik sudo[21344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 01:00:20 bcik sudo[21342]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:20 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 15 01:00:20 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 15 01:00:20 bcik volumio[21024]: error: Cannot start Volumio Streaming Daemon Oct 15 01:00:20 bcik volumio[21024]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 15 01:00:20 bcik volumio[21024]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 15 01:00:20 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 15 01:00:20 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 15 01:00:20 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 15 01:00:20 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 15 01:00:20 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 15 01:00:20 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 15 01:00:20 bcik sudo[21344]: pam_unix(sudo:session): session closed for user root Oct 15 01:00:20 bcik volumio[21024]: info: Remote SSH Stopped Oct 15 01:00:20 bcik volumio[21024]: info: Setting Geolocation for MyVolumio to eu6 Oct 15 01:00:20 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:20 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:20 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:20 bcik volumio[21024]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 15 01:00:21 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Oct 15 01:00:21 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:21 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:21 bcik go-librespot[21348]: go-librespot daemon starting... Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=debug msg="app state loaded" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+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 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+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 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+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 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=info msg="zeroconf server listening on port 37389" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=debug msg="obtained new client token: AAHUePvbV2yz1elv8ySgY+Rh9mAyJYzoDxPO+VEMZTvyA7a/kgJUNrehHMaoS0iGPZwiWFspxzz7nrxQ5NtE9lo3GYQqJUyVoHQIPhgFxI0BuHmeVYjDNVvTr2u/E+ZxzH+dQe1kkcdCmxeBFVWEHCiWjxZQuaQ2iSwPVsX2K2XN+t+5ni29qm5SpCheRtQ1aZXDGeXClsdxdtayA7pFpLQkqpVAywl4EkR6jVhWt9IFV1aPNpH8" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=debug msg="completed challenge" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:21 bcik go-librespot[21349]: time="2026-10-15T01:00:21+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:21 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:21 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:21 bcik volumio[21024]: info: Updating MyVolumio device info Oct 15 01:00:21 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:21 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:21 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:21 bcik volumio[21024]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 15 01:00:22 bcik systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 15 01:00:22 bcik systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 15 01:00:22 bcik systemd[1]: welcome.service: Deactivated successfully. Oct 15 01:00:22 bcik systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 15 01:00:22 bcik systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 15 01:00:22 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:22 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:22 bcik systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 15 01:00:22 bcik welcome[21375]: Resolved ip:[1] 192.168.10.236 Oct 15 01:00:22 bcik systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 15 01:00:22 bcik systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 15 01:00:22 bcik volumio[21024]: info: Received Get System Info Oct 15 01:00:22 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 01:00:22 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 01:00:22 bcik volumio[21024]: info: Discovery: Getting this device information Oct 15 01:00:22 bcik volumio[21024]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:22 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:22 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 01:00:22 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 01:00:22 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 01:00:23 bcik volumio5-onboarding[9231]: time=2026-10-15T01:00:23.764+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 15 01:00:24 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Oct 15 01:00:24 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:24 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:24 bcik go-librespot[21383]: go-librespot daemon starting... Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=debug msg="app state loaded" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+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 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+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 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=info msg="zeroconf server listening on port 34747" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=debug msg="obtained new client token: AAHFKOVISUuLMVHznOGcxT3Umv6fCrsZYceV7yk+jvFRBr1pPmY3jXrwg4aA1gaA1bLaMtw0B5hMci71atyM0RmSDTuafWgbTMq6SkoK03nz88xpsuPtNaGi+rGJ+V3C+glX0d8Mpx5X6tuGAOhZFoJ2ETp/hea1GPmwUBRs8w2nbfN9pFjLZlhHgdXUe7WUj8q6tVlIZIzRN3M1RSinBlD1HIlUDlPJ7d8gIiYCQ9HiSHuHWHEsxiA=" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=debug msg="completed challenge" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:24 bcik go-librespot[21384]: time="2026-10-15T01:00:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:24 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:24 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:24 bcik volumio[21024]: info: MYVOLUMIO: Adding device Oct 15 01:00:24 bcik volumio[21024]: info: MYVOLUMIO: Evaluating Server Oct 15 01:00:25 bcik volumio[21024]: info: Setting Geolocation for MyVolumio to eu6 Oct 15 01:00:25 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:25 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:25 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:25 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:25 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:25 bcik volumio[21024]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 15 01:00:26 bcik volumio[21024]: info: Updating MyVolumio device info Oct 15 01:00:26 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:26 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:26 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:26 bcik volumio[21024]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 15 01:00:28 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Oct 15 01:00:28 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:28 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:28 bcik go-librespot[21411]: go-librespot daemon starting... Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="app state loaded" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=info msg="zeroconf server listening on port 38519" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="obtained new client token: AAG23AB5Vo8Ksz44dRdg46cQ7a0kF1ga5p9vuH3/1rDMnfftSNDDf+Qfhsqq4GB8LfYAAIwwVsjV0b6NYMxXbIM/KfQlnimEamDuxIvtE2qds0GCLdprz/xLc0PaIJxD4TraVGwhcKurXb4bhPIJOnDPUdx25EzA1oPMu8aMFpFVw6wPuSTkbyZvGhGogX4jITq6lcRBGOosDhM9ZUwTXBKLaIoK2MAxEV/7VsWtiJ7npxaQgbxP" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=debug msg="completed challenge" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:28 bcik go-librespot[21412]: time="2026-10-15T01:00:28+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:28 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:28 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:28 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:28 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:31 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:31 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Oct 15 01:00:31 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:31 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:31 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:31 bcik go-librespot[21437]: go-librespot daemon starting... Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=debug msg="app state loaded" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+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 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+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 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=info msg="zeroconf server listening on port 34319" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=debug msg="obtained new client token: AAFkxOIulItBpB8WPN+6v7aNKO5+1lRkQEB/Y3m/ZXY03HY8nXkojezBAVyi07MGi9a04BjPIBRFk4Sxo4F1+taFtE4Sa2tVHBpCBCUhvvH0xFLBGnU+oRNiMjeuXFpKK2g+3NlY8ZT6FfGaXuzJ1/mn3xy2GpI+NB27cgZ/EjZwvybpH0JFg56zhdfDgc5D0Wuh0Yto+gdNgztURirXHOxcv39q8aGMhGAgqRiB/SviS0SnIEmAeHs=" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=debug msg="completed challenge" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:31 bcik go-librespot[21438]: time="2026-10-15T01:00:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:31 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:31 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:34 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 01:00:34 bcik volumio[21024]: 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 15 01:00:34 bcik volumio[21024]: info: CoreCommandRouter::volumioGetState Oct 15 01:00:34 bcik volumio[21024]: info: CorePlayQueue::getTrack 0 Oct 15 01:00:34 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:34 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:35 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Oct 15 01:00:35 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:35 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:35 bcik go-librespot[21447]: go-librespot daemon starting... Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="app state loaded" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=info msg="zeroconf server listening on port 39785" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="obtained new client token: AAFJ906Q4AQfhOG3678bMjrxWS1I+CHXizWNpi0L+4xNGBvllVNvPjtX7b9hZPVxpRQMr+kf8unlfsDwlh900NglNXcLRzbIehAvs62Z4gBIa2yq+hVvmLcqrkw1p7HhsMJH4SKtBWV3ApejgQP0YbJKgzAAYtJhQ3Dd+QEykL6JUw/RHKrPsOJKEseW4z1Xms8fkGoN6+tp3JfSpRidHQX6zANvJaT5YTHsI255hLQVAMZp/r6t" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=debug msg="completed challenge" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:35 bcik go-librespot[21448]: time="2026-10-15T01:00:35+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:35 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:35 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:37 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:37 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:38 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Oct 15 01:00:38 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:38 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:38 bcik go-librespot[21457]: go-librespot daemon starting... Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=debug msg="app state loaded" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+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 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+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 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+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 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=info msg="zeroconf server listening on port 32965" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=debug msg="obtained new client token: AAH6aobUA4vETxYck//KBspPp8N0N+/3jcbw092A2XMR9eT2yQ8y5GpH6QLpKKdYbV30dNtREQW/zJ/wyucWs4D+MAcyLyr23JfjtFfS4OTY3AE46tiUwoZC3Au9xxRVlO01oTy6O5QmiqKEvqF4k3pCFzJvelXsqHifytxPkg9qUfL3VUSjaIkchtJnG5XnILwsZ9gdAzAqbcp5bH32oVaKyc+dGyCZWEVcFgjeKtOnk3NLBxRXEwM=" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=debug msg="completed challenge" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:38 bcik go-librespot[21458]: time="2026-10-15T01:00:38+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:38 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:38 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::volumioGetBrowseSources Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:39 bcik volumio[21024]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 15 01:00:39 bcik volumio[21024]: info: Completed starting MyVolumio Plugin Oct 15 01:00:39 bcik volumio[21024]: [Metrics] CommandRouter: 30s 648.70ms Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::volumiosetStartupVolume Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::Close All Modals sent Oct 15 01:00:39 bcik volumio[21024]: info: CoreCommandRouter::Close All Modals sent Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 15 01:00:40 bcik volumio-remote-updater[9031]: Test mode disabled Oct 15 01:00:40 bcik volumio-remote-updater[9031]: Alpha mode disabled Oct 15 01:00:40 bcik volumio-remote-updater[9031]: Alpha legacy test mode disabled Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 15 01:00:40 bcik volumio[21024]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 15 01:00:40 bcik volumio[21024]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 15 01:00:40 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:40 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:42 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Oct 15 01:00:42 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:42 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:42 bcik go-librespot[21488]: go-librespot daemon starting... Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=debug msg="app state loaded" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+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 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+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 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+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 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=info msg="zeroconf server listening on port 44577" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=debug msg="obtained new client token: AAHKmj0R5xcxPD9Q6aStl4RyOFdkhczG5FtdKxPg5tginCzZbL9ui4iPXA63RLwJ7C/2xMZn/ajx0Yy6myyloSLUiA3IqVXqwS/hkgWU0lc8V8j6wyVXsElo1IepCwcJfodwqzpOQxtZ33PN+jISojnBDk4X9i+x5B55YNV4IiZjayfc3v+TJpfP7U7C+Jyq8lqi2yyT6Et6SgmU39EtPX7oKb+HVrvTM3L+HzZ64ASCj96YhCKp" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=debug msg="completed challenge" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:42 bcik go-librespot[21489]: time="2026-10-15T01:00:42+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:42 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:42 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:43 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:43 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:45 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Oct 15 01:00:45 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:45 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:45 bcik go-librespot[21498]: go-librespot daemon starting... Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=debug msg="app state loaded" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+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 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+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 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+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 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=info msg="zeroconf server listening on port 33575" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=debug msg="obtained new client token: AAEEJ+N8H1LMOormZ3vU+pRZFW7JxLfRy1EBHCoPMNBT5jPrh/AKpj11jWajbQcalCfH8GVCB6VTaye//EWhxq+aGS1HKlyHen7D0uNfn/38LTRZULyWkOOmKF8vX5ccERjsGIpG/e18lr87UakHyAezcLUzzr+luWssRhEOAgScJgHURBfbq82ViBNmsInUuhRcHFn89pQt/XuTtPSI1cZEZAbUxhBVUB6WVqy3l6k2LYtwpKQoWLA=" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=debug msg="completed challenge" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:45 bcik go-librespot[21499]: time="2026-10-15T01:00:45+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:45 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:45 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:46 bcik volumio[21024]: info: BOOT COMPLETED Oct 15 01:00:46 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:46 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:49 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Oct 15 01:00:49 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:49 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:49 bcik go-librespot[21508]: go-librespot daemon starting... Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=debug msg="app state loaded" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+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 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+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 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+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 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=info msg="zeroconf server listening on port 41029" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=debug msg="obtained new client token: AAE6tYt2wm8FLN9ymoZXysjeBtLeYBH++EUyQPdJPJ6ZjBLy9InOKMK5JLN9W/52XiIdhU3NzxOChWpJTsIWPVZ5Jy8MxGUlYRz2mdCivMxXiHrTREl59qPbySHkqGSS5VUY4omSjjTKCtxZu2j1sBIvmiLIMtohqs1XpTojqIbprXr2Hqj7E0pRf+S4UstwDgB8n6quwQko2JbSvcfVSkoPZtJX6XT9btAxUTqUuYfROzJ11Poo" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+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 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=debug msg="completed challenge" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:49 bcik go-librespot[21509]: time="2026-10-15T01:00:49+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:49 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:49 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:49 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:49 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:52 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:52 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:52 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Oct 15 01:00:52 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:52 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:52 bcik go-librespot[21532]: go-librespot daemon starting... Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=debug msg="app state loaded" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+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 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+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 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+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 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=info msg="zeroconf server listening on port 33763" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=debug msg="obtained new client token: AAH8mXoaSNrp+IX1TzdXBSfeLT3SukdXJnhNVmZch6aF9QHd0KmXsSc70Poz7YzogsKUxxOEpn/OWda+Fzh2/dEuSSOMdmCMvtVwSWUiZZtgkqz7Qxcb9b7XB3i544SPS2102oCnqQkfinJ3p7/Vlny/F9vTg0QrVM2YBMDT+uD7jBxmmduujH6TG7S0r/mIaUu6rnk+1p902aPUzQoLUTfM6t0MY/40iLRRTzy6DTFi0uidI3M3DWY=" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=debug msg="completed challenge" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:52 bcik go-librespot[21533]: time="2026-10-15T01:00:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:52 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:52 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:55 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:55 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:56 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Oct 15 01:00:56 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:56 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:56 bcik go-librespot[21542]: go-librespot daemon starting... Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=debug msg="app state loaded" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+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 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+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 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=info msg="zeroconf server listening on port 42139" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=debug msg="obtained new client token: AAEl2KyfpKWdecWmpX76J7zX8EceYXjmHf/PRgqXJ1N7EJCvkLk2REzZbyxCHhKkCf93jRyqZXQoOPl7LQi/12Eg8f3o5RI/URhibwxw8HX+pxMSzu5UvIR9zj567lhuajTqwJ/0joJ3ARsXE7wkL4q7uENG2fI6oFszd1HGc5GA8erCghvQLdJF6MVcsJ8AjvJ2wm0fYFcZwfmO8EagfV9ZRmWVPyr5dR7QAuToDDE+VHDug3zj" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=debug msg="completed challenge" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:56 bcik go-librespot[21543]: time="2026-10-15T01:00:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:56 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:56 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:00:58 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:00:58 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:00:59 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Oct 15 01:00:59 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:59 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:00:59 bcik go-librespot[21566]: go-librespot daemon starting... Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=debug msg="app state loaded" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+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 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+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 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+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 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=info msg="zeroconf server listening on port 44715" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=debug msg="obtained new client token: AAGU1BthV2HxxoByE9+x+q/PDUMEwDBvWzWPMnYBt39CTBwom7oP1BUglb87eR66yLwX7kQvajYIXSXQGBZvnBJ+Tjubm1pzbxv7NwzlCzobMlL2/N9JqawEG+7Iyqd2Ckb6CzWewDOXE7cjrGt/KviOiv2mD7rOwRK2TV5oaT3HX5eJq/nbTR3uJKq97fSadugJS1KPHCL/kgN9AvlqURJ/wwLFuwa+4lzO0KkDr9EtYEyJr7jqKEw=" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=debug msg="completed keyexchange" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=debug msg="completed challenge" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:00:59 bcik go-librespot[21567]: time="2026-10-15T01:00:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:00:59 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:00:59 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:01 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:01 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:03 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Oct 15 01:01:03 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:03 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:03 bcik go-librespot[21577]: go-librespot daemon starting... Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=debug msg="app state loaded" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+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 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+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 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=info msg="zeroconf server listening on port 44803" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=debug msg="obtained new client token: AAH3X9HgXSjqPQ7rMYMgrO8MW45qPebbKD0Ww9/L/OAh5Cw5LJ2stidlSmCBttnBirmh8XuMzF41YgwqZ2zCkJ6MKXySfIVbOAIexux+eleAwJ9WM87Jk0+VDuuxgsM9wskWcOtblkfu1+qMecVTww9muCblhh6UTv1qd9q9Thdu5a25mfBDodXx8LNAFZ8L/HCMe52oz5vT+u3rnuSVGXC0tYuhGM9XgvcRYAvTLkyN0nzdxIix" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=debug msg="completed challenge" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:03 bcik go-librespot[21578]: time="2026-10-15T01:01:03+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:01:03 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:03 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:04 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:04 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:06 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Oct 15 01:01:06 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:06 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:06 bcik go-librespot[21587]: go-librespot daemon starting... Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=debug msg="app state loaded" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+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 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+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 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+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 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=info msg="zeroconf server listening on port 35763" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=debug msg="obtained new client token: AAF+G2DgzVDFsMx1l23lT01XdsKzUYRrv+HqGJFIzZV4uaJTEYi8Whw0RCOY1aUAJpleuwjxSbRJye7dSKSomoC3XtnNWSFj8qUsnIZ3i1hQ0uBm/pJX3Go1TMR/p19xz/Yz0cpbDUDoPmmIYxxEbWkvKxmqTPOueBwg93moSfEX82x4TTCfFdEHTv+K65bMS4YVmaxNOE5x4voGKZlVUzGOOv+RujUytHVzBB97Qzp7WJLesYJJacI=" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+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 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=debug msg="completed challenge" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01:01:06+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:06 bcik go-librespot[21588]: time="2026-10-15T01: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 15 01:01:06 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:06 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:07 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:07 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:10 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Oct 15 01:01:10 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:10 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:10 bcik go-librespot[21611]: go-librespot daemon starting... Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=debug msg="app state loaded" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+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 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+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 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=info msg="zeroconf server listening on port 33129" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=debug msg="obtained new client token: AAFKUSkOQg8YZGgoNk5ISBghrJD2fDXFxfoev2BNJQltviXbgzzgQ/V1e6e1qhh9stAwqsRe0NTgJ6iqCUc5OwkzHzEAp2wJwhYsOHDuOj7MsRaUDud7gBjX6zhvfjChNzxbRoUdIqYvwWTV18kVcznzQqwxiMBloF49AeRZe6QK3N4cW7ann0o8m6RQChv7FlsekZ7iegnQ983a6HVEivLIf19pJblnwfjt4MqBsnhSwSTE334X" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=debug msg="completed challenge" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:10 bcik go-librespot[21612]: time="2026-10-15T01:01:10+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:01:10 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:10 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:10 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:10 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:13 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Oct 15 01:01:13 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:13 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:13 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:13 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:13 bcik go-librespot[21621]: go-librespot daemon starting... Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=debug msg="app state loaded" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+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 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+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 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+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 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=info msg="zeroconf server listening on port 44569" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=debug msg="obtained new client token: AAG6H5BSk4ephw2/CWgIWXkl6NSO8Sp3Q4K5NvmC2yWWXIhfHMEXCzarMm5tlYesSYwJoUAoxKFQy+8q2KATk8lKHC7lmWa17UgAZ8GdU+4hKwTSEOieXjnDiC9vza1qVogdGW1/jIOWhxurEtvUK6dz4M6T3GLQYkscKBkQE5/zESebSV74c4Eq0HKct8Y5q39SdOWteMzrLQRDJprlnmFHHriZ7vyBB8+IIKcSITrcLaI9gOSwZf4=" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=debug msg="completed challenge" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01:01:13+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:13 bcik go-librespot[21622]: time="2026-10-15T01: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 15 01:01:13 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:13 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:16 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:16 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:17 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Oct 15 01:01:17 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:17 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:17 bcik go-librespot[21632]: go-librespot daemon starting... Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=debug msg="app state loaded" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+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 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+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 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+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 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=info msg="zeroconf server listening on port 37127" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=debug msg="obtained new client token: AAGEcpTWsPssgR2fVA9rL4coJgv/Izrj2XDdzydAK1RdbS/t5RJL7wbkxi6TGOzI2vZwxjnyiN01m9vvUVlPP32EXwopqXpcj8WSHIj8i7NwlEXUVwA5nQC5fzyz5Icu3EaPvB8E0QS18pO2OKzWXaKFdovN9er9nM0ANGVLZ20ddrxpse78ldd04HKuBgYvFqFdS2UACVoIIwF7BjC6EFEa5PyKk3kaPWdRCOohmIuIZBYNqLcB" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=debug msg="completed challenge" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:17 bcik go-librespot[21633]: time="2026-10-15T01:01:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:01:17 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:17 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:19 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:19 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:20 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Oct 15 01:01:20 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:20 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:20 bcik go-librespot[21656]: go-librespot daemon starting... Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=debug msg="app state loaded" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+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 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+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 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=info msg="zeroconf server listening on port 39799" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=debug msg="obtained new client token: AAFczRm9MwxvGGyHv/6XttM9cbKLzfkHOtemowbYlvkI1EBdsGtOpqmPnuTLd8PSN4gP5doIUY8jaPNYTJ/7KM1k9f1FTjmN2ezjyzjC522mjzlTpB+VZd9cTtuA/vTxIoYxV28lDCfsgipaTsp0hfnu9delXOpOYZwVrb4dqQ0e0slJz68IZ0xoRre/RdIq5HNzgPTRseYUWCadWTcboNQGLOpnb3OV27LPA1sAF5FEYWW5EDrBQpE=" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=debug msg="completed challenge" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01:01:20+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:20 bcik go-librespot[21657]: time="2026-10-15T01: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 15 01:01:20 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:20 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:22 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:22 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:24 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Oct 15 01:01:24 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:24 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:24 bcik go-librespot[21667]: go-librespot daemon starting... Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=debug msg="app state loaded" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+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 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+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 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=info msg="zeroconf server listening on port 39149" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=debug msg="obtained new client token: AAFJbIqMOEbbQfA8OK0qP1PgicOsiENCEZAvEOt3PvydQVyIqEcCw6q5o+FC7oIjJzL3+7Qdov8DTtDTLRGn5/KLidgIsjAA7i/XPCyHCLeE5CTGVamTPATnGNQUO2/mvhs6wExTFiAGa1IJKifLyFOnk+by172wngmLr2HKWcuETgBfmcyBZpHo7sff1yAqSDacFChksokcrIAjJ6EhZFvbyxLu6XwUdPeTHaPznGMvb36lGTrl" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=debug msg="completed challenge" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:24 bcik go-librespot[21668]: time="2026-10-15T01:01:24+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:01:24 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:24 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:25 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:25 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:27 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Oct 15 01:01:27 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:27 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:27 bcik go-librespot[21678]: go-librespot daemon starting... Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=debug msg="app state loaded" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+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 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+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 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+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 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=info msg="zeroconf server listening on port 40037" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=debug msg="obtained new client token: AAFzaqr7we1AJuGGSrabyhStpywGuKa0Z6faqdbZPVkAVtqg+rwO2SDLAo2GXkDyDlEe0CpXlyh2afHR0VhxzxkJ0a/cCB8ZbqQkExaVFn4hVTFvgpvMtISXjdDGjbFHlQtN/td+5vcFU4WA6+dsoXnBLMSLx8uc+Jh+iH8A1C/D4LD+bQ5NZVJgM04zPk6f28NuQ6NBZe3zd7LFFBDq//V37qYUh2mNlhYsZvUl5XlPLPoB9mM4rlc=" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=debug msg="completed challenge" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01:01:27+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:27 bcik go-librespot[21679]: time="2026-10-15T01: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 15 01:01:27 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:27 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:28 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:28 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:31 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Oct 15 01:01:31 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:31 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:31 bcik go-librespot[21702]: go-librespot daemon starting... Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=debug msg="app state loaded" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+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 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+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 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=info msg="zeroconf server listening on port 41841" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=debug msg="obtained new client token: AAE1EIZI8PUHf2H48ywst/KftOfyRKnh3Glpevl+063zlb4kFAjTmYr17aQkShH30XxqJSrsElVyChy2AUo2irkXEJJGcInQdEkjcNqlCMMhNWVgDInhaQpfGVuLim3pnx79sw08WamzijWBtFo3x1NjTl8WseJIGmD3/z71e14AlkV5T05f8Xz/q3TJXyPChxXpIiuLeuler9FDX0yQlU953LGK+bZ2Q+fKUC4MbiEe6J8goAcX" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=debug msg="completed challenge" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:31 bcik go-librespot[21703]: time="2026-10-15T01:01:31+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 15 01:01:31 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:31 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:31 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:31 bcik volumio[21024]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:34 bcik systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Oct 15 01:01:34 bcik systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:34 bcik systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 15 01:01:34 bcik go-librespot[21712]: go-librespot daemon starting... Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=info msg="running go-librespot 0.6.2" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=debug msg="app state loaded" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 15 01:01:34 bcik volumio[21024]: info: Initializing connection to go-librespot Websocket Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=debug msg="new websocket client" Oct 15 01:01:34 bcik volumio[21024]: info: Connection to go-librespot Websocket established Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+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 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+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 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+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 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=info msg="zeroconf server listening on port 46679" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=debug msg="obtained new client token: AAH5j5UK3Dg3rdeNcqfXDE6Fg5UpdQ0DHEX/eOFmOpLYzs/m5hKmEW1mkirQzP17vjiqeqeBwjmVuhogr8SffoBEfQyIrqAgdYiDte0Iet6ytuMBaSRWHZSqrSRS08I0iKm6i7/dAncYgjJDx6wJOkimmOQMMPMHKU8lY7/AUyZlUHLdxgcaoxkp3lVDz7PdLh25AFtHaxKoks6uIKQHIwPCm2QmGLhVxDPZt0gpEiUc4fw0/8POPR0=" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=debug msg="completed keyexchange" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=debug msg="completed challenge" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01:01:34+02:00" level=info msg="authenticated AP" username="la***pl" Oct 15 01:01:34 bcik go-librespot[21713]: time="2026-10-15T01: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 15 01:01:34 bcik systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 15 01:01:34 bcik systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 15 01:01:34 bcik volumio[21024]: info: Connection to go-librespot Websocket closed Oct 15 01:01:37 bcik volumio[21024]: info: Getting Spotify volume Oct 15 01:01:37 bcik volumio[21024]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 01:01:37 bcik volumio[21024]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 15 01:01:37 bcik volumio[21024]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 15 01:01:37 bcik volumio[21024]: errno: -111, Oct 15 01:01:37 bcik volumio[21024]: code: 'ECONNREFUSED', Oct 15 01:01:37 bcik volumio[21024]: syscall: 'connect', Oct 15 01:01:37 bcik volumio[21024]: address: '127.0.0.1', Oct 15 01:01:37 bcik volumio[21024]: port: 9879, Oct 15 01:01:37 bcik volumio[21024]: response: undefined Oct 15 01:01:37 bcik volumio[21024]: } Oct 15 01:01:37 bcik volumio[21024]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 01:01:37 bcik sudo[21736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 01:00' Oct 15 01:01:37 bcik sudo[21736]: 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"