Mar 24 03:30:00 katarynka volumio[5668]: info: Volumio Calling Home
Mar 24 03:30:01 katarynka volumio[5774]: Starting albumart workers
Mar 24 03:30:01 katarynka volumio[5777]: Starting albumart workers
Mar 24 03:30:01 katarynka volumio[5779]: Starting albumart workers
Mar 24 03:30:02 katarynka sudo[5873]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 24 03:30:02 katarynka volumio[5668]: info: Discovery: adding 4d616d77-6a8c-4da2-85a4-840d70f98e51
Mar 24 03:30:02 katarynka sudo[5873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:02 katarynka volumio[5668]: info: Discovery: Found device katarynka
Mar 24 03:30:02 katarynka volumio[5668]: info: CoreCommandRouter::volumioGetState
Mar 24 03:30:02 katarynka volumio[5668]: info: CorePlayQueue::getTrack 0
Mar 24 03:30:02 katarynka sudo[5873]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:02 katarynka sudo[5875]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 24 03:30:02 katarynka sudo[5875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:02 katarynka volumio[5668]: info: Discovery: this is already registered,  4d616d77-6a8c-4da2-85a4-840d70f98e51
Mar 24 03:30:02 katarynka sudo[5875]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:02 katarynka volumio[5668]: info: Discovery: Found device katarynka
Mar 24 03:30:02 katarynka volumio[5668]: info: CoreCommandRouter::volumioGetState
Mar 24 03:30:02 katarynka volumio[5668]: info: CorePlayQueue::getTrack 0
Mar 24 03:30:02 katarynka volumio[5668]: info: MPD Permissions set
Mar 24 03:30:02 katarynka volumio[5668]: info: MPD Permissions set
Mar 24 03:30:02 katarynka volumio[5668]: info: VolumeController:: Volume=96 Mute =false
Mar 24 03:30:02 katarynka volumio[5668]: info: CoreStateMachine::pushState
Mar 24 03:30:02 katarynka volumio[5668]: info: CorePlayQueue::getTrack 0
Mar 24 03:30:02 katarynka volumio[5668]: info: CoreCommandRouter::volumioPushState
Mar 24 03:30:02 katarynka volumio-remote-updater[622]: [2026-03-24 03:30:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774319399 101
Mar 24 03:30:02 katarynka volumio[5668]: 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: 1
Mar 24 03:30:02 katarynka volumio[5668]: info: Volumio called home
Mar 24 03:30:02 katarynka volumio[5668]: info: Spotify config file written
Mar 24 03:30:02 katarynka sudo[5879]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Mar 24 03:30:02 katarynka sudo[5879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:03 katarynka systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon...
Mar 24 03:30:03 katarynka systemd[1]: go-librespot-daemon.service: Deactivated successfully.
Mar 24 03:30:03 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:03 katarynka volumio[5668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Mar 24 03:30:03 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:03 katarynka go-librespot[5881]: go-librespot daemon starting...
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka sudo[5879]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01:00" level=debug msg="app state loaded"
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:03 katarynka volumio[5668]: info: No need to fix Spotify hosts
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01: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]"
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01: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]"
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01: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]"
Mar 24 03:30:03 katarynka go-librespot[5882]: time="2026-03-24T03:30:03+01:00" level=info msg="zeroconf server listening on port 45391"
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01:00" level=debug msg="obtained new client token: AACIiRCEHMWmoiqy3iZytCAffJHvYTLgipqHYIqfsD/a5/tjfDMp9XqWcBHFhYM0YdO8ju9c5nHYA2ylJXQZ+Kcb2CqUPPWbZuH1a+t2T5j6ePLqA9i583KLD4i/Q2tGtp3zIvmdMiLK5xQgoPXAYxbAZD5YSUr47BJYzTnWbWHs4wVFNcaKZJp9lKcWteOPHhisvQMKwYxi6hjQaZUdDO+pH/JwesYfEHPWAsSjUANOQ/jGaOdwrwiQ"
Mar 24 03:30:04 katarynka volumio[5668]: info: Starting Shairport Sync
Mar 24 03:30:04 katarynka volumio[5668]: info: Starting Shairport Sync
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01: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"
Mar 24 03:30:04 katarynka volumio[5668]: info: Starting Shairport Sync
Mar 24 03:30:04 katarynka sudo[5920]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused"
Mar 24 03:30:04 katarynka sudo[5920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:80"
Mar 24 03:30:04 katarynka sudo[5922]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 24 03:30:04 katarynka sudo[5922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:04 katarynka sudo[5927]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Mar 24 03:30:04 katarynka sudo[5927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:04 katarynka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01:00" level=debug msg="completed challenge"
Mar 24 03:30:04 katarynka systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 24 03:30:04 katarynka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 24 03:30:04 katarynka systemd[1]: shairport-sync.service: Consumed 2.339s CPU time.
Mar 24 03:30:04 katarynka sudo[5925]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 24 03:30:04 katarynka sudo[5925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:04 katarynka go-librespot[5882]: time="2026-03-24T03:30:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:04 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:04 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:04 katarynka volumio[5668]: info: An error occurred while refreshing Spotify Token Error: Bad Request
Mar 24 03:30:04 katarynka volumio[5668]: info: CoreCommandRouter::volumioGetState
Mar 24 03:30:04 katarynka volumio[5668]: info: CorePlayQueue::getTrack 0
Mar 24 03:30:04 katarynka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 24 03:30:04 katarynka sudo[5927]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:04 katarynka sudo[5922]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:04 katarynka volumio[5668]: info: Upmpdcli Daemon Started
Mar 24 03:30:04 katarynka volumio[5668]: info: Shairport-Sync Started
Mar 24 03:30:04 katarynka volumio[5668]: Error adding Membership: Error: addMembership EINVAL
Mar 24 03:30:04 katarynka sudo[5920]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:04 katarynka sudo[5925]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:04 katarynka volumio[5668]: info: Shairport-Sync Started
Mar 24 03:30:04 katarynka volumio[5668]: info: Shairport-Sync Started
Mar 24 03:30:06 katarynka mpd[5853]: 2026-03-24T03:30:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 24 03:30:06 katarynka systemd[1]: Started mpd.service - Music Player Daemon.
Mar 24 03:30:06 katarynka sudo[5814]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:06 katarynka sudo[5824]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:07 katarynka volumio[5668]: info: Completed starting Core Plugins
Mar 24 03:30:07 katarynka volumio[5668]: info: -------------------------------------------
Mar 24 03:30:07 katarynka volumio[5668]: info: -----    MyVolumio plugins startup     ----
Mar 24 03:30:07 katarynka volumio[5668]: info: -------------------------------------------
Mar 24 03:30:07 katarynka volumio[5668]: info: [MyVolumio PluginManager] Fetching plans data....
Mar 24 03:30:07 katarynka volumio[5668]: error: MPD error: The expression evaluated to a falsy value:
Mar 24 03:30:07 katarynka volumio[5668]:   assert.ok(self.idling)
Mar 24 03:30:07 katarynka volumio[5668]: error: The expression evaluated to a falsy value:
Mar 24 03:30:07 katarynka volumio[5668]:   assert.ok(self.idling)
Mar 24 03:30:07 katarynka volumio[5668]: info: go-librespot daemon successfully initialized
Mar 24 03:30:07 katarynka volumio[5668]: error: updateQueue error: null
Mar 24 03:30:07 katarynka volumio[5668]: info: MPD running with PID5853
Mar 24 03:30:07 katarynka volumio[5668]:  ,establishing connection
Mar 24 03:30:07 katarynka volumio[5668]: error: updateQueue error: null
Mar 24 03:30:07 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Mar 24 03:30:07 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:07 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:07 katarynka go-librespot[5951]: go-librespot daemon starting...
Mar 24 03:30:07 katarynka go-librespot[5952]: time="2026-03-24T03:30:07+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:07 katarynka go-librespot[5952]: time="2026-03-24T03:30:07+01:00" level=debug msg="app state loaded"
Mar 24 03:30:07 katarynka go-librespot[5952]: time="2026-03-24T03:30:07+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01: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]"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01: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]"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01: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]"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01:00" level=info msg="zeroconf server listening on port 34689"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01:00" level=debug msg="obtained new client token: AADN94DARCn08TFxKHrP8VpYDQK4i09AzoY+XfYc4KhnVj53065qWSgPbLIRN6DbqoDF+sY4aaBF3iX2IPkTviBO5oofqOk9iaGZuJRzdUaZ3XxNyNHf/GValD4PqZ9/vYd1hXWsUjWnGzSdn9DvfxmjodRSlcf8FvYnMMFwrvMiNoCu32TlMOhWLBSc5ndmuKYGf0mtePdEz8R7vifQytcL985RTI0EtXAbKvjneXGawyzHWmsGUDOHHpk="
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01: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"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01:00" level=debug msg="completed challenge"
Mar 24 03:30:08 katarynka go-librespot[5952]: time="2026-03-24T03:30:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:08 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:08 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:10 katarynka sudo[5960]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 24 03:30:10 katarynka sudo[5960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:10 katarynka sudo[5962]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 24 03:30:10 katarynka sudo[5962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:10 katarynka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Mar 24 03:30:10 katarynka systemd[1]: mpd_monitor.service: Deactivated successfully.
Mar 24 03:30:10 katarynka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Mar 24 03:30:10 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:10 katarynka systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Mar 24 03:30:10 katarynka sudo[5962]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:10 katarynka sudo[5960]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:10 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:10 katarynka mpd_monitor.sh[5965]: MPD Monitor Service: Starting MPD Monitor Service
Mar 24 03:30:10 katarynka volumio[5668]: info: Successfully started MPD Monitor
Mar 24 03:30:10 katarynka volumio[5668]: info: Successfully started MPD Monitor
Mar 24 03:30:11 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Mar 24 03:30:11 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:11 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:11 katarynka go-librespot[5983]: go-librespot daemon starting...
Mar 24 03:30:11 katarynka go-librespot[5984]: time="2026-03-24T03:30:11+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:11 katarynka go-librespot[5984]: time="2026-03-24T03:30:11+01:00" level=debug msg="app state loaded"
Mar 24 03:30:11 katarynka go-librespot[5984]: time="2026-03-24T03:30:11+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:12 katarynka volumio[5668]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01: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]"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01: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]"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01: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]"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01:00" level=info msg="zeroconf server listening on port 44895"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01:00" level=debug msg="obtained new client token: AAAl3WHDzoep8/Na5e2/LZ47W62lQdeFdEXMy5knoeGMZ+JkeChof71al7FGpgi6ARKgr/rivk3Sb7QPDLsW1M8MSz15fvumSEN/qcieRuw3mKdP76WSb/mQAra48CdjlzsNl0tdf1TJ6Y46iVK55cYHU2c69dPTjEvoDuIyVZxRsaKdlb5xh2vFLPpOI8u9tIeBodju7mJEmRppv+IyncizG7xw4VNHc+t4Ely8qVzKKDhD/F5GmYsd"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01: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"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01:00" level=debug msg="completed challenge"
Mar 24 03:30:12 katarynka go-librespot[5984]: time="2026-03-24T03:30:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:12 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:12 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:13 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:13 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin multiroom to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin qobuzconnect to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 24 03:30:15 katarynka volumio[5668]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 24 03:30:15 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Mar 24 03:30:15 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:15 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:15 katarynka go-librespot[5991]: go-librespot daemon starting...
Mar 24 03:30:15 katarynka go-librespot[5992]: time="2026-03-24T03:30:15+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:15 katarynka go-librespot[5992]: time="2026-03-24T03:30:15+01:00" level=debug msg="app state loaded"
Mar 24 03:30:15 katarynka go-librespot[5992]: time="2026-03-24T03:30:15+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01: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]"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01: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]"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01: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]"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01:00" level=info msg="zeroconf server listening on port 33233"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01:00" level=debug msg="obtained new client token: AADfBn6rHjJDbNcjzx0RSnnmITq9ZG3o30X0ZI1YjfaWStFITT8G9CX2DsHB6Q4qMESOcE76tluE0xVViJrtuEOiPmkAOTpHh9yInY32mRxqCEDy36rVUFb0PTE4W5J5z9gmlBz76vyoHYTl/9Yjz9JeW+ePRVv6Ju5Jvt/YXMeUH7u3cx1fQe2yfUd8VyzrpP1LozMQQti14PYB86g3mlZDnLNC3JESdgil8o+hnWibbGP2lAZlAL9FoPo="
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01:00" level=debug msg="completed challenge"
Mar 24 03:30:16 katarynka go-librespot[5992]: time="2026-03-24T03:30:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:16 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:16 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:17 katarynka volumio[5668]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 24 03:30:17 katarynka volumio[5668]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 24 03:30:17 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:17 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:17 katarynka volumio[5668]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 24 03:30:17 katarynka volumio[5668]: info: MyVolumio login type: Token
Mar 24 03:30:17 katarynka volumio[5668]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 24 03:30:17 katarynka volumio[5668]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Mar 24 03:30:19 katarynka volumio[5668]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 24 03:30:19 katarynka volumio[5668]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 24 03:30:19 katarynka volumio[5668]: info: Streaming services startup
Mar 24 03:30:19 katarynka volumio[5668]: info: Starting Streaming Daemon
Mar 24 03:30:19 katarynka sudo[6000]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 24 03:30:19 katarynka sudo[6000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:19 katarynka volumio[5668]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 24 03:30:19 katarynka sudo[6000]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:19 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:19 katarynka volumio[5668]: error: Cannot start Volumio Streaming Daemon
Mar 24 03:30:19 katarynka volumio[5668]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 24 03:30:19 katarynka volumio[5668]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 24 03:30:19 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:19 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Mar 24 03:30:19 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:19 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:19 katarynka go-librespot[6006]: go-librespot daemon starting...
Mar 24 03:30:19 katarynka go-librespot[6007]: time="2026-03-24T03:30:19+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:19 katarynka go-librespot[6007]: time="2026-03-24T03:30:19+01:00" level=debug msg="app state loaded"
Mar 24 03:30:19 katarynka go-librespot[6007]: time="2026-03-24T03:30:19+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01: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]"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01: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]"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01: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]"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01:00" level=info msg="zeroconf server listening on port 38453"
Mar 24 03:30:20 katarynka volumio[5668]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01:00" level=debug msg="obtained new client token: AADVwwWWaNuIHqE4Cc7nWJ21j9GpmMz8Wn7aDUlRwWcBrXGBegWLwwg0J2sD/K/sQzZLDKTYFJ+f8jQUrEoXNWOFO126t87E/U8A4jsaeVtHaWVHS1azo+8kwtU09VDSwHOFqmQlAyXHBETyTEFaGqeHvGYR6pEc/F4cpX975Optje8BVkwQL+/NGDy8D64Z8zNbQvwP8qyu58OJak24P6hUypdyDZdtf+LhyP4OEBsMLvmPvLhNGZ0H9ek="
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01:00" level=debug msg="completed challenge"
Mar 24 03:30:20 katarynka go-librespot[6007]: time="2026-03-24T03:30:20+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:20 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:20 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:20 katarynka volumio[5668]: info: MyVolumio token set successfully
Mar 24 03:30:20 katarynka volumio[5668]: info: MYVOLUMIO: Adding device
Mar 24 03:30:20 katarynka volumio[5668]: info: MYVOLUMIO: Evaluating Server
Mar 24 03:30:21 katarynka volumio[5668]: info: MyVolumio status changed
Mar 24 03:30:21 katarynka volumio[5668]: info: Streaming services startup
Mar 24 03:30:21 katarynka volumio[5668]: info: Starting Streaming Daemon
Mar 24 03:30:21 katarynka volumio[5668]: info: Removing browser output: myVolumio user plan is not superstar
Mar 24 03:30:21 katarynka volumio[5668]: info: Removing audio output:
Mar 24 03:30:21 katarynka volumio[5668]: info: Stoppping Tunnel 1
Mar 24 03:30:21 katarynka sudo[6050]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 24 03:30:21 katarynka sudo[6050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:21 katarynka sudo[6052]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Mar 24 03:30:21 katarynka sudo[6052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 24 03:30:21 katarynka 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.
Mar 24 03:30:21 katarynka 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.
Mar 24 03:30:21 katarynka sudo[6050]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:21 katarynka volumio[5668]: error: Cannot start Volumio Streaming Daemon
Mar 24 03:30:21 katarynka volumio[5668]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 24 03:30:21 katarynka volumio[5668]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 24 03:30:21 katarynka 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.
Mar 24 03:30:21 katarynka 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.
Mar 24 03:30:21 katarynka 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.
Mar 24 03:30:21 katarynka 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.
Mar 24 03:30:21 katarynka sudo[6052]: pam_unix(sudo:session): session closed for user root
Mar 24 03:30:21 katarynka volumio[5668]: info: Remote SSH Stopped
Mar 24 03:30:21 katarynka volumio[5668]: info: Setting Geolocation for MyVolumio to eu11
Mar 24 03:30:21 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:21 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:21 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:21 katarynka volumio[5668]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 24 03:30:22 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:22 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:22 katarynka volumio[5668]: info: Updating MyVolumio device info
Mar 24 03:30:22 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:22 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:22 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:23 katarynka volumio[5668]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 24 03:30:23 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Mar 24 03:30:23 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:23 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:23 katarynka go-librespot[6055]: go-librespot daemon starting...
Mar 24 03:30:23 katarynka go-librespot[6056]: time="2026-03-24T03:30:23+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:23 katarynka go-librespot[6056]: time="2026-03-24T03:30:23+01:00" level=debug msg="app state loaded"
Mar 24 03:30:23 katarynka go-librespot[6056]: time="2026-03-24T03:30:23+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01: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]"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01: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]"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01: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]"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01:00" level=info msg="zeroconf server listening on port 40499"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01:00" level=debug msg="obtained new client token: AAAKv/7gAJFNoBgJFp5vWpayictTKvcAQJSnwll6gSxtywcII0ijxeCIbBGLojmV3+oqwuZU9/UA5shxbN87u0UIogQU0TzLZux5OxpqUbWk28+NI1IT9jefHmpC5KW6QGKPx8ppBD6XTYw5owYnLUc+L0yoGqTWYwPt5ZQDaLfOuHDu6CL4dfSAm3Iz40eEbaY1gMzXAG3cf3ACTsSx+bzyt5utIxRkGioVjpBX0ilf0frZyrvsYn1bzEA="
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01:00" level=debug msg="completed challenge"
Mar 24 03:30:24 katarynka go-librespot[6056]: time="2026-03-24T03:30:24+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:24 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:24 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:25 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:25 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:25 katarynka volumio[5668]: info: MYVOLUMIO: Adding device
Mar 24 03:30:25 katarynka volumio[5668]: info: MYVOLUMIO: Evaluating Server
Mar 24 03:30:26 katarynka volumio[5668]: info: Setting Geolocation for MyVolumio to eu4
Mar 24 03:30:26 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:26 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:26 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:27 katarynka volumio[5668]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 24 03:30:27 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Mar 24 03:30:27 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:27 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:27 katarynka go-librespot[6081]: go-librespot daemon starting...
Mar 24 03:30:27 katarynka go-librespot[6082]: time="2026-03-24T03:30:27+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:27 katarynka go-librespot[6082]: time="2026-03-24T03:30:27+01:00" level=debug msg="app state loaded"
Mar 24 03:30:27 katarynka go-librespot[6082]: time="2026-03-24T03:30:27+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:27 katarynka volumio[5668]: info: Updating MyVolumio device info
Mar 24 03:30:27 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:27 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:27 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:28 katarynka volumio[5668]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01: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]"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01: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]"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01: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]"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01:00" level=info msg="zeroconf server listening on port 40651"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01:00" level=debug msg="obtained new client token: AACZVZgaEyL35wXUD6vNjhrzHXAlYxREr7Hxr+W776BGPN2uM0bu5/DUMIuhuBavjBtDZj42supNjotHvVCDrgs2xPtTu7QtLUb9RJITKOhJRz65sL+umxnNUzdi11QERwjET53DNBHv4Q34uY8m4AgFCHl0jrjOxQbnHGkj8p/b7TviSzcM0auiPxH7PK4BRAcw3j08UNPWkbVkiB49zgWTRgEwshBomC0QAxSSnothohws+fVvtHLCchk="
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01:00" level=debug msg="completed challenge"
Mar 24 03:30:28 katarynka go-librespot[6082]: time="2026-03-24T03:30:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:28 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:28 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:28 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:28 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:30 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 24 03:30:30 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:30 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 24 03:30:30 katarynka volumio-remote-updater[622]: Test mode disabled
Mar 24 03:30:30 katarynka volumio-remote-updater[622]: Alpha mode disabled
Mar 24 03:30:30 katarynka volumio-remote-updater[622]: Alpha legacy test mode disabled
Mar 24 03:30:30 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 24 03:30:31 katarynka volumio[5668]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for BLE notification issues
\nFix for mDNS/BCT
\nFix wireless init on Pi
\nFix raspi-config abuse on Pi
\n
\nNEW ADDITIONS
\n\nBluetooth party mode
\nUpdate Tidal SDK
\nUpdate Qobuz Connect SDK
\n\n","title":"Update v4.103","updateavailable":true}
Mar 24 03:30:31 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 24 03:30:31 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:31 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:31 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Mar 24 03:30:31 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:31 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:31 katarynka go-librespot[6110]: go-librespot daemon starting...
Mar 24 03:30:31 katarynka go-librespot[6111]: time="2026-03-24T03:30:31+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:31 katarynka go-librespot[6111]: time="2026-03-24T03:30:31+01:00" level=debug msg="app state loaded"
Mar 24 03:30:31 katarynka go-librespot[6111]: time="2026-03-24T03:30:31+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01: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]"
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01: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]"
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01: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]"
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01:00" level=info msg="zeroconf server listening on port 44943"
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01:00" level=debug msg="obtained new client token: AACwD4vcYqgbreLMILBHkkC4qDq4/K7DlrBQq0ibEtfbHGejlvf/R+zoBxUatavc8Inj41MbakHebt8G736loBoI+AYJbHXvTjeqw34vRUsjdbsyWdj8GnLvvHMosZhvAOrWtjZPw+C1Wdysg1zPk/0E+JnffFCiM2RLCYPEQVsbdJZthNIu6NDarM7OMUFc5DSi0oRg9WB+EygwZa39CD+9Ta9v3MOxhvd6ruuHs5liiq17gzeYZuSc5FU="
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:32 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01:00" level=debug msg="completed challenge"
Mar 24 03:30:32 katarynka volumio[5668]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Mar 24 03:30:32 katarynka go-librespot[6111]: time="2026-03-24T03:30:32+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:32 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:32 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:32 katarynka volumio[5668]: info: CoreCommandRouter::volumioGetState
Mar 24 03:30:32 katarynka volumio[5668]: info: CorePlayQueue::getTrack 0
Mar 24 03:30:34 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:34 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:35 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Mar 24 03:30:35 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:35 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:35 katarynka go-librespot[6118]: go-librespot daemon starting...
Mar 24 03:30:35 katarynka go-librespot[6119]: time="2026-03-24T03:30:35+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:35 katarynka go-librespot[6119]: time="2026-03-24T03:30:35+01:00" level=debug msg="app state loaded"
Mar 24 03:30:35 katarynka go-librespot[6119]: time="2026-03-24T03:30:35+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01: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]"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01: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]"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01: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]"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01:00" level=info msg="zeroconf server listening on port 44811"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01:00" level=debug msg="obtained new client token: AABtdMBr8nGa76XJSJvQeKhj5K1ZZ3RDdXdEjR5eJjWuTdkG3Z8OkT3S7Za9F+in+eONk5Ehp8Z2jZPzJIxgdv5Vwyypyu3svIK+sz3Oup0q6LEwVOZZUkYSMiYhUYAsN/ihsHHAw0aPq3eXTPfriA3/2Trq051t4MYxdVuf1fevyTFJ3As4RvcFonKDKRD0hKkBAoza0xHTOKKwH1gEKqKVFw8i5LlWRdqQLPfzYWnn3KFt2YC1ZUlG"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01:00" level=debug msg="completed challenge"
Mar 24 03:30:36 katarynka go-librespot[6119]: time="2026-03-24T03:30:36+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:36 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:36 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 24 03:30:37 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 24 03:30:37 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:37 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:39 katarynka volumio[5668]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Mar 24 03:30:39 katarynka volumio[5668]: info: Completed starting MyVolumio Plugin
Mar 24 03:30:39 katarynka volumio[5668]:  [Metrics]  CommandRouter:  53s 462.77ms 
Mar 24 03:30:39 katarynka volumio[5668]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 24 03:30:39 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 24 03:30:39 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 24 03:30:39 katarynka volumio[5668]: info: CoreCommandRouter::Close All Modals sent
Mar 24 03:30:39 katarynka volumio[5668]: info: CoreCommandRouter::Close All Modals sent
Mar 24 03:30:39 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Mar 24 03:30:39 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:39 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:39 katarynka go-librespot[6128]: go-librespot daemon starting...
Mar 24 03:30:39 katarynka go-librespot[6129]: time="2026-03-24T03:30:39+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:39 katarynka go-librespot[6129]: time="2026-03-24T03:30:39+01:00" level=debug msg="app state loaded"
Mar 24 03:30:39 katarynka go-librespot[6129]: time="2026-03-24T03:30:39+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01: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]"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01: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]"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01: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]"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01:00" level=info msg="zeroconf server listening on port 39825"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01:00" level=debug msg="obtained new client token: AABVeXpaNg+c9uN1lT0/wAiIwwsIf7XxtUCKLTRxZ9vkV+gFs8t1EY2LHutlXR8DPluqVgsxsWT0BqsOm9xGaPZU18BD7NMcHy2+IU8XulOfvWcNH4/T3+enfS5qnqyZ+cmapfXNW/P4BV3f9TFVyrWPSbQN+SCbL6NTVyNiJNaFgpj783xTIpHWTHUZJ0atL+mjijZyZHq/Fg2UOd1E5tEtfgcb6Id0v/KNlU14KsiFVtnDquqilt/u1wU="
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01:00" level=debug msg="completed challenge"
Mar 24 03:30:40 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 24 03:30:40 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 24 03:30:40 katarynka volumio[5668]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 24 03:30:40 katarynka go-librespot[6129]: time="2026-03-24T03:30:40+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:40 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:40 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:40 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:40 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:43 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:43 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:43 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Mar 24 03:30:43 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:43 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:43 katarynka go-librespot[6151]: go-librespot daemon starting...
Mar 24 03:30:43 katarynka go-librespot[6152]: time="2026-03-24T03:30:43+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:43 katarynka go-librespot[6152]: time="2026-03-24T03:30:43+01:00" level=debug msg="app state loaded"
Mar 24 03:30:43 katarynka go-librespot[6152]: time="2026-03-24T03:30:43+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01: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]"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01: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]"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01: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]"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01:00" level=info msg="zeroconf server listening on port 42441"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01:00" level=debug msg="obtained new client token: AAAyk0+/BVYZJWD0xo9gzZE05t8khZ9IqcZi6YrBxEkCHgmOOJX7Tvsq9cVnhhtMvlXOcMK1bzWDVzdDRf0LpGL187Q/V5p61zmwOdxvKJLrOa/MiX6G2dVcRfLOfHmVBaaLzxfMPNSzlIXXRq9t/k9pAaTLczwSHTJSIiFXPyr1IC+AjIWkYW06XqSlI7/XZXYE1aX2N9WuUco211H+rGJjOGcVgQA00O+x/tc812uFgdaVKWBzdgsDvXE="
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01:00" level=debug msg="completed challenge"
Mar 24 03:30:44 katarynka go-librespot[6152]: time="2026-03-24T03:30:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:44 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:44 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:46 katarynka volumio[5668]: info: BOOT COMPLETED
Mar 24 03:30:46 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:46 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:47 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Mar 24 03:30:47 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:47 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:47 katarynka go-librespot[6159]: go-librespot daemon starting...
Mar 24 03:30:47 katarynka go-librespot[6160]: time="2026-03-24T03:30:47+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:47 katarynka go-librespot[6160]: time="2026-03-24T03:30:47+01:00" level=debug msg="app state loaded"
Mar 24 03:30:47 katarynka go-librespot[6160]: time="2026-03-24T03:30:47+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01: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]"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01: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]"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01: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]"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01:00" level=info msg="zeroconf server listening on port 34559"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01:00" level=debug msg="obtained new client token: AAAlIakxZLHXR4obvguKeVE1CZtGfICIvtvphKEqGth7E16t00ClwFdWK+ygHy3iPfHhnRlyPKAX91jGRAQc4oTS+sfeawXo3et0LMMCuaINxXrU1vPBalAA6nTwu/K96Cn+RZl50oM4kgYxxHVu43OsnfJONKIz6RmQJDyKQ/76YUQgO81gwaxpmgjXKE+dqQyofckGKGnWcA7vVYpZfkY039mK6kr97v4vIwHMKobzecSfK0L8MJSxIXI="
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01:00" level=debug msg="completed challenge"
Mar 24 03:30:48 katarynka go-librespot[6160]: time="2026-03-24T03:30:48+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:48 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:48 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:49 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:49 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:51 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Mar 24 03:30:51 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:51 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:51 katarynka go-librespot[6185]: go-librespot daemon starting...
Mar 24 03:30:51 katarynka go-librespot[6186]: time="2026-03-24T03:30:51+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:51 katarynka go-librespot[6186]: time="2026-03-24T03:30:51+01:00" level=debug msg="app state loaded"
Mar 24 03:30:51 katarynka go-librespot[6186]: time="2026-03-24T03:30:51+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01: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]"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01: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]"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01: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]"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01:00" level=info msg="zeroconf server listening on port 46301"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01:00" level=debug msg="obtained new client token: AADPs741pemz2KjI5cMBCXgxTpsOpFs+RAnc7OXfSh/BnTqZr8h/M/u3I6P1dbu+E54iwgVHhvmM1LR4sHwrjHXisQBQkps9lE8a5LcJdAGedOgJdltIqM4zjuYICJHQVYj8hVXRglNgnYj2N/lpxCxrSKSuW6OrfXR3fkdrJskNrFHxFZomrLoJ3hJs3lqEy41SOrzYnZksi3MQDcd4eXcAqyl9pFaLjxNLHQsDo7uGBgiMA+OvXOFZ1zM="
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01:00" level=debug msg="completed challenge"
Mar 24 03:30:52 katarynka go-librespot[6186]: time="2026-03-24T03:30:52+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:52 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:52 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:52 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:52 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:55 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Mar 24 03:30:55 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:55 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:55 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:55 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:55 katarynka go-librespot[6193]: go-librespot daemon starting...
Mar 24 03:30:55 katarynka go-librespot[6194]: time="2026-03-24T03:30:55+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:55 katarynka go-librespot[6194]: time="2026-03-24T03:30:55+01:00" level=debug msg="app state loaded"
Mar 24 03:30:55 katarynka go-librespot[6194]: time="2026-03-24T03:30:55+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01: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]"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01: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]"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01: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]"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01:00" level=info msg="zeroconf server listening on port 38807"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01:00" level=debug msg="obtained new client token: AADLkmx3C+/pi8PeSmA3UEURXEoWu3IA4goUP7hLLWpkv3iY9ppksHBcljVT3aIbX96rqy3ax5+SikL7EaOQ62Qh830se4HhIwuTkR6krRXCrC92Kvh3VanQ5WcEaI5FEQ2CZ0ph5Xs3kZlr1ofu1tnlWwW2rMt5wRbtrV5K61qeThAvasMA6etEDhY+fCdoacKYkRuF/4Kok8akkH4ntOtZkYMOt1mJSt+WOXEtPC0+V2vtn0gFNxfM12s="
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01:00" level=debug msg="completed keyexchange"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01:00" level=debug msg="completed challenge"
Mar 24 03:30:56 katarynka go-librespot[6194]: time="2026-03-24T03:30:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:30:56 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:30:56 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:30:58 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:30:58 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:30:59 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Mar 24 03:30:59 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:59 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:30:59 katarynka go-librespot[6201]: go-librespot daemon starting...
Mar 24 03:30:59 katarynka go-librespot[6202]: time="2026-03-24T03:30:59+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:30:59 katarynka go-librespot[6202]: time="2026-03-24T03:30:59+01:00" level=debug msg="app state loaded"
Mar 24 03:30:59 katarynka go-librespot[6202]: time="2026-03-24T03:30:59+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01: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]"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01: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]"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01: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]"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01:00" level=info msg="zeroconf server listening on port 45129"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01:00" level=debug msg="obtained new client token: AABqgMlZ/lATE+HV4CckCxn3uflWXIM3cBZW19dX9Uu/wHYF7+mvQGhdcBxztiI2Jk+bCXCCscBaghiyX95PolmdMJ4o2bHtQeEikcxrxjy+a+TQoSBuvud5V2W4aK75sEFPJsxkAFwYVQMNZidNIyKDbfvwgMB1GNGAzSNu4VasnXdAZWEcFaM7LX0LCRo7saeA2iiOO1wpmwgxVPjo6hAWp0WO9sW9xtA+JJcslgKCM4OP73FHSxzBlqs="
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01:00" level=debug msg="completed challenge"
Mar 24 03:31:00 katarynka go-librespot[6202]: time="2026-03-24T03:31:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:00 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:00 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:01 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:01 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:03 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Mar 24 03:31:03 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:03 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:03 katarynka go-librespot[6224]: go-librespot daemon starting...
Mar 24 03:31:03 katarynka go-librespot[6225]: time="2026-03-24T03:31:03+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:03 katarynka go-librespot[6225]: time="2026-03-24T03:31:03+01:00" level=debug msg="app state loaded"
Mar 24 03:31:03 katarynka go-librespot[6225]: time="2026-03-24T03:31:03+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01: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]"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01: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]"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01: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]"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01:00" level=info msg="zeroconf server listening on port 32849"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01:00" level=debug msg="obtained new client token: AACaNcT/QrzbDTmuJN8v/+x14pgigAJE0J243+PNZONftB8gacHnlSSerz8IRd1nxXpyiDiBSUQjH6LFCgsQ7TXKS86luBB1tIsaHFNLNg/NpajLA4NMSMdbXtUTxKZttUoXV4QXihmhFdi4FbNxxOSZTvvrw11JrX5WFG3RR+5gAHP6AN6yCqYpEWp5QQ/WRVeNVewKxVP5UNxvTlbuO6PxmrKpgqkkmUc+tjbxNUKudmGQGzrPISRsNqA="
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01:00" level=debug msg="completed challenge"
Mar 24 03:31:04 katarynka go-librespot[6225]: time="2026-03-24T03:31:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:04 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:04 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:04 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:04 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:07 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Mar 24 03:31:07 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:07 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:07 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:07 katarynka go-librespot[6232]: go-librespot daemon starting...
Mar 24 03:31:07 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:07 katarynka go-librespot[6233]: time="2026-03-24T03:31:07+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:07 katarynka go-librespot[6233]: time="2026-03-24T03:31:07+01:00" level=debug msg="app state loaded"
Mar 24 03:31:07 katarynka go-librespot[6233]: time="2026-03-24T03:31:07+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01: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]"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01: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]"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01: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]"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01:00" level=info msg="zeroconf server listening on port 42685"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01:00" level=debug msg="obtained new client token: AABvNnHvSqu+B6/kcfDFe+qio33eqgvWiHEUd70+2uhdjahzotv+S0AeyGxY09qQ0UF72jecD+eXon48avUrEDgFtnsO7Ku+PC/R6x1X2jTDJOLfE8My39/ChypyZnyGi5j/upuqnnlrSLR2cD4vAbVdOb/qwnCGJVrycKy2nqPIs8gI6EAuKO1pF485tawJwTcaksvkotckbC7FAQcH1fFK+oWWVGY2hG/yfTkGlo27yb2YseW0xdYPock="
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01:00" level=debug msg="completed challenge"
Mar 24 03:31:08 katarynka go-librespot[6233]: time="2026-03-24T03:31:08+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:08 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:08 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:10 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:10 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:11 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Mar 24 03:31:11 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:11 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:11 katarynka go-librespot[6257]: go-librespot daemon starting...
Mar 24 03:31:11 katarynka go-librespot[6258]: time="2026-03-24T03:31:11+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:11 katarynka go-librespot[6258]: time="2026-03-24T03:31:11+01:00" level=debug msg="app state loaded"
Mar 24 03:31:11 katarynka go-librespot[6258]: time="2026-03-24T03:31:11+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01: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]"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01: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]"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01: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]"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01:00" level=info msg="zeroconf server listening on port 36111"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01:00" level=debug msg="obtained new client token: AABYJYbc6RC1beKW8SelVQddKEe560CnhnXbM1fYDhZMknxhggHU6/BMB5rfim/EiLShgo24dh7kfg1Xi/geFYEH8lzB22Gyt64r/pm64rIrfIjYB9Mu/6AzQfqW8ymTQodvDOmHRtjn3aMgI4dlmxuIf4YSDBba1bTstx9k3OxxD8BWu9bNI96rWa9fy2rlVeIImsZSulXbEGhBbO13fOplItCBopfQOngZguhW3+m+Obno7fymYmeC"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01:00" level=debug msg="completed challenge"
Mar 24 03:31:12 katarynka go-librespot[6258]: time="2026-03-24T03:31:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:12 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:12 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:13 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:13 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:15 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Mar 24 03:31:15 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:15 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:15 katarynka go-librespot[6265]: go-librespot daemon starting...
Mar 24 03:31:15 katarynka go-librespot[6266]: time="2026-03-24T03:31:15+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:15 katarynka go-librespot[6266]: time="2026-03-24T03:31:15+01:00" level=debug msg="app state loaded"
Mar 24 03:31:15 katarynka go-librespot[6266]: time="2026-03-24T03:31:15+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01: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]"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01: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]"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01: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]"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01:00" level=info msg="zeroconf server listening on port 43373"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01:00" level=debug msg="obtained new client token: AAAER5WUKZCORfoRqlFuR6DaHEXkLaCJZ2lcSyeXPqf2NV5msfdwnArJRa/GbQdEVQQU9knqxrm35Vrv2RK8ylnE0YWiuEcw2AnbQP6zAIZQxqJI9GMz5uMtEtRHgSwyoL08DZQxJnVVoJruaHL0SqJ1zb2ywAU5Yk947rkWxlBk0JwVSJCY4mO9iSnAMgkntXRqtw9k9INJn+tPTpFQndXi651uIHL7qg4qTUZlJXxHVCBI8JFCP7p+aRc="
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01:00" level=debug msg="completed challenge"
Mar 24 03:31:16 katarynka go-librespot[6266]: time="2026-03-24T03:31:16+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:16 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:16 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:16 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:16 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:19 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Mar 24 03:31:19 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:19 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:19 katarynka go-librespot[6273]: go-librespot daemon starting...
Mar 24 03:31:19 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:19 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:19 katarynka go-librespot[6274]: time="2026-03-24T03:31:19+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:19 katarynka go-librespot[6274]: time="2026-03-24T03:31:19+01:00" level=debug msg="app state loaded"
Mar 24 03:31:19 katarynka go-librespot[6274]: time="2026-03-24T03:31:19+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01: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]"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01: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]"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01: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]"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01:00" level=info msg="zeroconf server listening on port 36617"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01:00" level=debug msg="obtained new client token: AADcLK8tKNtTg8x+ipN9/LQTrpMXGy+RwSKpXBnEtnA/FX15Fjym1R6mjpsS3ogwRDBcAy7hBDOKLyRaxS1bBdnNNnq8NFB5q5eShe9/vabQ+GjNEzbBUxa3Cm1b41NS91QjuuGECbEpsVA2249oyPMP29yMWp6IN6sJYhmzo/B3iy0aZuiupQ9I+rK1nbDcrzLxExd8m2PAPYxEvFNC5xEx+V0J0rJCu+FOmW04PZE/XCjkqBM3WhNWFtM="
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:20 katarynka go-librespot[6274]: time="2026-03-24T03:31:20+01:00" level=debug msg="completed challenge"
Mar 24 03:31:21 katarynka go-librespot[6274]: time="2026-03-24T03:31:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:21 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:21 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:22 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:22 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:24 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Mar 24 03:31:24 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:24 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:24 katarynka go-librespot[6297]: go-librespot daemon starting...
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01:00" level=debug msg="app state loaded"
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01: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]"
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01: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]"
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01: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]"
Mar 24 03:31:24 katarynka go-librespot[6298]: time="2026-03-24T03:31:24+01:00" level=info msg="zeroconf server listening on port 43773"
Mar 24 03:31:25 katarynka go-librespot[6298]: time="2026-03-24T03:31:25+01:00" level=debug msg="obtained new client token: AABFghONnujdBPopB/Q9dXGLBIFScT6FkE4gqmdsKw3H2s/O784zTPS+L4kqP0vsF/acl7crdQrRBwiv5F0mIVFLQiYPE7EpJinOcfH7bVkl68JhvPSkPIi31WiMVBG0XvFGM8QHfCrmerwejpLxqddkCMl7czklPAmkJvfoodHoHdjOkCXcPkND74H3ZrT2EugyjIOcVgGvIM2f3ScG+anZNeqbOXr85tt5Hp4r3En7Hbdbf3Htxipu"
Mar 24 03:31:25 katarynka go-librespot[6298]: time="2026-03-24T03:31:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:25 katarynka go-librespot[6298]: time="2026-03-24T03:31:25+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:25 katarynka go-librespot[6298]: time="2026-03-24T03:31:25+01:00" level=debug msg="completed challenge"
Mar 24 03:31:25 katarynka go-librespot[6298]: time="2026-03-24T03:31:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:25 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:25 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:25 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:25 katarynka volumio[5668]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:28 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Mar 24 03:31:28 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:28 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:28 katarynka go-librespot[6305]: go-librespot daemon starting...
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01:00" level=debug msg="app state loaded"
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:28 katarynka volumio[5668]: info: Initializing connection to go-librespot Websocket
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01:00" level=debug msg="new websocket client"
Mar 24 03:31:28 katarynka volumio[5668]: info: Connection to go-librespot Websocket established
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01: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]"
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01: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]"
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01: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]"
Mar 24 03:31:28 katarynka go-librespot[6306]: time="2026-03-24T03:31:28+01:00" level=info msg="zeroconf server listening on port 36593"
Mar 24 03:31:29 katarynka go-librespot[6306]: time="2026-03-24T03:31:29+01:00" level=debug msg="obtained new client token: AAAJnE70G38ZumeKdwgfrSUndgKnsEjZZRz9MY7HENepvURag0G+OcfgPDiQMJO/UcBxFZFbLqiM3oMnWysMC6sowoa5hQtBqoJxwM5uA0Rg10ochja9BGYqz9Bm/E8+T6pxvzfWDv3pvVEi59H0qGPU8vxRco1g2ZcVoezqvRT1GHlTKIJUydcg8LZUlyS8dnTru8NRsUedI2w1X/xlqysZI/NvWFAbxmELOHo/eWPOAdMv/XbQcaI5"
Mar 24 03:31:29 katarynka go-librespot[6306]: time="2026-03-24T03:31:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:29 katarynka go-librespot[6306]: time="2026-03-24T03:31:29+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:29 katarynka go-librespot[6306]: time="2026-03-24T03:31:29+01:00" level=debug msg="completed challenge"
Mar 24 03:31:29 katarynka go-librespot[6306]: time="2026-03-24T03:31:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:29 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:29 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:29 katarynka volumio[5668]: info: Connection to go-librespot Websocket closed
Mar 24 03:31:31 katarynka volumio[5668]: info: Getting Spotify volume
Mar 24 03:31:31 katarynka volumio[5668]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 24 03:31:31 katarynka volumio[5668]: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 24 03:31:31 katarynka volumio[5668]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Mar 24 03:31:31 katarynka volumio[5668]:   errno: -111,
Mar 24 03:31:31 katarynka volumio[5668]:   code: 'ECONNREFUSED',
Mar 24 03:31:31 katarynka volumio[5668]:   syscall: 'connect',
Mar 24 03:31:31 katarynka volumio[5668]:   address: '127.0.0.1',
Mar 24 03:31:31 katarynka volumio[5668]:   port: 9879,
Mar 24 03:31:31 katarynka volumio[5668]:   response: undefined
Mar 24 03:31:31 katarynka volumio[5668]: }
Mar 24 03:31:31 katarynka volumio[5668]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 24 03:31:32 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22.
Mar 24 03:31:32 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:32 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 24 03:31:32 katarynka go-librespot[6343]: go-librespot daemon starting...
Mar 24 03:31:32 katarynka go-librespot[6344]: time="2026-03-24T03:31:32+01:00" level=info msg="running go-librespot 0.4.0"
Mar 24 03:31:32 katarynka go-librespot[6344]: time="2026-03-24T03:31:32+01:00" level=debug msg="app state loaded"
Mar 24 03:31:32 katarynka go-librespot[6344]: time="2026-03-24T03:31:32+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01: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]"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01: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]"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01: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]"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01:00" level=info msg="zeroconf server listening on port 38989"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01:00" level=debug msg="obtained new client token: AAA4cVBqyiMBNFz4qdld3WChDpLilbLyy/PlVsCFRB8b4ejMTEP6HqE29e2EXR0pNf6Z87MZkddWTrzVChw7aaSPBnokm4SiJajhypBEmjPm8syiaCY0E9fkIJQRbJkg7sUDwuWORahDH7LkJFAHlqFeRsYg2bOfkKpGsqTNmlRpV+7ryguHStDdqlcpv3G8NWWpAmueLMmrxTONs7wlwHkLBbcZ5OB/5T9tNWMBN4pChG2bj3xjCQvC"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01:00" level=debug msg="completed keyexchange"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01:00" level=debug msg="completed challenge"
Mar 24 03:31:33 katarynka go-librespot[6344]: time="2026-03-24T03:31:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 24 03:31:33 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 03:31:33 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 24 03:31:33 katarynka sudo[6354]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 03:30'
Mar 24 03:31:33 katarynka sudo[6354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Dec  5 20:42:14 UTC 2025"
VOLUMIO_VERSION="4.073"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"