Mar 26 05:54:00 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:00 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:00 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:00 katarynka sudo[29311]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 26 05:54:00 katarynka sudo[29311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 26 05:54:00 katarynka sudo[29311]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:00 katarynka volumio[29127]: info: Volumio Calling Home
Mar 26 05:54:01 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Mar 26 05:54:01 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:01 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:01 katarynka go-librespot[29338]: go-librespot daemon starting...
Mar 26 05:54:01 katarynka go-librespot[29339]: time="2026-03-26T05:54:01+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:02 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:02 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:02 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:02 katarynka volumio[29127]: info: Discovery: adding 4d616d77-6a8c-4da2-85a4-840d70f98e51
Mar 26 05:54:02 katarynka volumio[29127]: info: Discovery: Found device katarynka
Mar 26 05:54:02 katarynka volumio[29127]: info: CoreCommandRouter::volumioGetState
Mar 26 05:54:02 katarynka volumio[29127]: info: CorePlayQueue::getTrack 0
Mar 26 05:54:02 katarynka sudo[29347]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 26 05:54:02 katarynka volumio[29127]: info: Discovery: this is already registered, 4d616d77-6a8c-4da2-85a4-840d70f98e51
Mar 26 05:54:02 katarynka sudo[29347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:02 katarynka volumio[29127]: info: Discovery: Found device katarynka
Mar 26 05:54:02 katarynka volumio[29127]: info: CoreCommandRouter::volumioGetState
Mar 26 05:54:02 katarynka volumio[29127]: info: CorePlayQueue::getTrack 0
Mar 26 05:54:02 katarynka sudo[29347]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:02 katarynka sudo[29349]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 26 05:54:02 katarynka sudo[29349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:02 katarynka volumio[29127]: info: VolumeController:: Volume=96 Mute =false
Mar 26 05:54:02 katarynka volumio[29127]: info: CoreStateMachine::pushState
Mar 26 05:54:02 katarynka volumio[29127]: info: CorePlayQueue::getTrack 0
Mar 26 05:54:02 katarynka volumio[29127]: info: CoreCommandRouter::volumioPushState
Mar 26 05:54:02 katarynka sudo[29349]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:02 katarynka volumio[29127]: info: MPD Permissions set
Mar 26 05:54:02 katarynka volumio[29127]: info: MPD Permissions set
Mar 26 05:54:02 katarynka volumio-remote-updater[686]: [2026-03-26 05:54:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774500839 101
Mar 26 05:54:02 katarynka volumio[29246]: Starting albumart workers
Mar 26 05:54:02 katarynka volumio[29127]: 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 26 05:54:02 katarynka volumio[29127]: info: Volumio called home
Mar 26 05:54:02 katarynka volumio[29127]: info: Spotify config file written
Mar 26 05:54:02 katarynka volumio[29249]: Starting albumart workers
Mar 26 05:54:02 katarynka go-librespot[29339]: time="2026-03-26T05:54:02+01:00" level=info msg="zeroconf server listening on port 38081"
Mar 26 05:54:02 katarynka sudo[29355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Mar 26 05:54:02 katarynka sudo[29355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:02 katarynka volumio[29247]: Starting albumart workers
Mar 26 05:54:02 katarynka systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon...
Mar 26 05:54:02 katarynka systemd[1]: go-librespot-daemon.service: Deactivated successfully.
Mar 26 05:54:02 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:03 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:03 katarynka volumio[29127]: 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 26 05:54:03 katarynka go-librespot[29357]: go-librespot daemon starting...
Mar 26 05:54:03 katarynka sudo[29355]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54:03+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54:03+01:00" level=debug msg="app state loaded"
Mar 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54:03+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:03 katarynka volumio[29127]: info: No need to fix Spotify hosts
Mar 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54: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-gae2.spotify.com:80]"
Mar 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54:03+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 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54:03+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 26 05:54:03 katarynka go-librespot[29359]: time="2026-03-26T05:54:03+01:00" level=info msg="zeroconf server listening on port 38843"
Mar 26 05:54:04 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:04 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:04 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:04 katarynka go-librespot[29359]: time="2026-03-26T05:54:04+01:00" level=debug msg="obtained new client token: AAAqiYBKfRLjgRjIO0795gY9ytbOjZWZndptDrupbk0cBDsZbTf0w+RmWVXpevSrHYgyb7Bg/2ysRcMhAX3ctDySKMFZzMEI0cHd9JUWmwbhsmum1rLvzuNw9um7leL2j5QFcQDR/V3P95/euQ4e7rY/lYpH/DmxdJrJJV6PNV5aBo820mpYVQSjfcdX7WpJhxnS8bBSK2HHKcufWZFs6XJq5t5y3mTc6OGTaVRA4fAlng3TLKAwj+3q"
Mar 26 05:54:04 katarynka go-librespot[29359]: time="2026-03-26T05:54:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:04 katarynka go-librespot[29359]: time="2026-03-26T05:54:04+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:04 katarynka go-librespot[29359]: time="2026-03-26T05:54:04+01:00" level=debug msg="completed challenge"
Mar 26 05:54:04 katarynka volumio[29127]: info: Starting Shairport Sync
Mar 26 05:54:04 katarynka volumio[29127]: info: Starting Shairport Sync
Mar 26 05:54:04 katarynka go-librespot[29359]: time="2026-03-26T05:54: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 26 05:54:04 katarynka volumio[29127]: info: Starting Shairport Sync
Mar 26 05:54:04 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:04 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:04 katarynka sudo[29379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 26 05:54:04 katarynka sudo[29379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:04 katarynka sudo[29381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 26 05:54:04 katarynka sudo[29381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:04 katarynka sudo[29377]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 26 05:54:04 katarynka sudo[29377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:04 katarynka sudo[29383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Mar 26 05:54:04 katarynka sudo[29383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:04 katarynka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 26 05:54:04 katarynka systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 26 05:54:04 katarynka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 26 05:54:04 katarynka systemd[1]: shairport-sync.service: Consumed 6.636s CPU time.
Mar 26 05:54:04 katarynka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 26 05:54:04 katarynka volumio[29127]: info: CoreCommandRouter::volumioGetState
Mar 26 05:54:04 katarynka volumio[29127]: info: CorePlayQueue::getTrack 0
Mar 26 05:54:04 katarynka sudo[29379]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:04 katarynka sudo[29381]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:04 katarynka volumio[29127]: info: Shairport-Sync Started
Mar 26 05:54:04 katarynka volumio[29127]: Error adding Membership: Error: addMembership EINVAL
Mar 26 05:54:04 katarynka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 26 05:54:04 katarynka systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 26 05:54:04 katarynka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 26 05:54:05 katarynka volumio[29127]: info: Shairport-Sync Started
Mar 26 05:54:05 katarynka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 26 05:54:05 katarynka sudo[29377]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:05 katarynka sudo[29383]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:05 katarynka volumio[29127]: info: Upmpdcli Daemon Started
Mar 26 05:54:05 katarynka volumio[29127]: info: Shairport-Sync Started
Mar 26 05:54:05 katarynka volumio[29127]: info: An error occurred while refreshing Spotify Token Error: Bad Request
Mar 26 05:54:06 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:06 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:06 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:07 katarynka volumio[29127]: info: go-librespot daemon successfully initialized
Mar 26 05:54:07 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Mar 26 05:54:07 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:07 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:07 katarynka go-librespot[29432]: go-librespot daemon starting...
Mar 26 05:54:07 katarynka go-librespot[29433]: time="2026-03-26T05:54:07+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:07 katarynka go-librespot[29433]: time="2026-03-26T05:54:07+01:00" level=debug msg="app state loaded"
Mar 26 05:54:07 katarynka go-librespot[29433]: time="2026-03-26T05:54:07+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:07 katarynka mpd[29320]: 2026-03-26T05:54:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 26 05:54:07 katarynka systemd[1]: Started mpd.service - Music Player Daemon.
Mar 26 05:54:07 katarynka sudo[29292]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:07 katarynka sudo[29282]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:07 katarynka volumio[29127]: info: Completed starting Core Plugins
Mar 26 05:54:07 katarynka volumio[29127]: info: -------------------------------------------
Mar 26 05:54:07 katarynka volumio[29127]: info: ----- MyVolumio plugins startup ----
Mar 26 05:54:07 katarynka volumio[29127]: info: -------------------------------------------
Mar 26 05:54:07 katarynka volumio[29127]: info: [MyVolumio PluginManager] Fetching plans data....
Mar 26 05:54:07 katarynka go-librespot[29433]: time="2026-03-26T05:54:07+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 26 05:54:07 katarynka go-librespot[29433]: time="2026-03-26T05:54:07+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 26 05:54:07 katarynka go-librespot[29433]: time="2026-03-26T05:54:07+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 26 05:54:08 katarynka go-librespot[29433]: time="2026-03-26T05:54:08+01:00" level=info msg="zeroconf server listening on port 38955"
Mar 26 05:54:08 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:08 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:08 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:08 katarynka volumio[29127]: error: MPD error: The expression evaluated to a falsy value:
Mar 26 05:54:08 katarynka volumio[29127]: assert.ok(self.idling)
Mar 26 05:54:08 katarynka volumio[29127]: error: The expression evaluated to a falsy value:
Mar 26 05:54:08 katarynka volumio[29127]: assert.ok(self.idling)
Mar 26 05:54:08 katarynka volumio[29127]: error: updateQueue error: null
Mar 26 05:54:08 katarynka volumio[29127]: info: MPD running with PID29320
Mar 26 05:54:08 katarynka volumio[29127]: ,establishing connection
Mar 26 05:54:08 katarynka volumio[29127]: error: updateQueue error: null
Mar 26 05:54:08 katarynka go-librespot[29433]: time="2026-03-26T05:54:08+01:00" level=debug msg="obtained new client token: AADQNBvZ6yMflhhqE32eOGCwpq+4F4DGbiYTjY6aYTLOArx1AHto7JoL4cjOhmkfszUngvvQpK+BNxBbIx/cuRExfZCj9DUeR4ZBnvGaAeTWcodcfelqD7DtG0493rE6zhnT+yQLlVc/Wpx+GdA7gm7Lic2q5RSoXToHdKDmCWG0YHaE6i6kzOzrAqk2qFQTRbFkiSh1xcQRa8gqCp8JWdtH3k9sy87WrD4aYY6xiKlsXl0o2cw4h1EW"
Mar 26 05:54:08 katarynka go-librespot[29433]: time="2026-03-26T05:54:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:08 katarynka go-librespot[29433]: time="2026-03-26T05:54:08+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:08 katarynka go-librespot[29433]: time="2026-03-26T05:54:08+01:00" level=debug msg="completed challenge"
Mar 26 05:54:09 katarynka go-librespot[29433]: time="2026-03-26T05:54:09+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 26 05:54:09 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:09 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:10 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:10 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:10 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:10 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:10 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:10 katarynka sudo[29460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 26 05:54:10 katarynka sudo[29460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:10 katarynka sudo[29462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 26 05:54:10 katarynka sudo[29462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:11 katarynka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Mar 26 05:54:11 katarynka systemd[1]: mpd_monitor.service: Deactivated successfully.
Mar 26 05:54:11 katarynka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Mar 26 05:54:11 katarynka systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Mar 26 05:54:11 katarynka sudo[29462]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:11 katarynka sudo[29460]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:11 katarynka volumio[29127]: info: Successfully started MPD Monitor
Mar 26 05:54:11 katarynka mpd_monitor.sh[29465]: MPD Monitor Service: Starting MPD Monitor Service
Mar 26 05:54:11 katarynka volumio[29127]: info: Successfully started MPD Monitor
Mar 26 05:54:12 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:12 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:12 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:12 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2.
Mar 26 05:54:12 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:12 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:12 katarynka go-librespot[29469]: go-librespot daemon starting...
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=debug msg="app state loaded"
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54: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 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54: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 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54: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 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=info msg="zeroconf server listening on port 36473"
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=debug msg="obtained new client token: AACAJR3gqO6SbY5+m62gIXUygMS17X+oAulyTn5KUI9M5IOIj5HI3x9o0qc64FjTqxENaaH8vXMrtdBZFrJY06x7PC+umcasCdQZb7GZHwfFC3FJ6DMxsfIupH6iaerzIcRMxVdgf3E27Q1h0RbLP/fjD8V3pprSuT+W0G+4xDj/m8vR6umy+QAQKt28a5WgupDUU8ZzwrqZYp/U1dPJxUR4EwztpCsmPOHcrLGOJ51UZGlDErZ0QQtYBvA="
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:12 katarynka go-librespot[29470]: time="2026-03-26T05:54:12+01:00" level=debug msg="completed challenge"
Mar 26 05:54:12 katarynka volumio[29127]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Mar 26 05:54:13 katarynka go-librespot[29470]: time="2026-03-26T05:54:13+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 26 05:54:13 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:13 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:13 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:13 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:14 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:14 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:14 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:16 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:16 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:16 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:16 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3.
Mar 26 05:54:16 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:16 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:16 katarynka go-librespot[29477]: go-librespot daemon starting...
Mar 26 05:54:16 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:16 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+01:00" level=debug msg="app state loaded"
Mar 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin multiroom to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin qobuzconnect to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 26 05:54:16 katarynka volumio[29127]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+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 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+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 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+01:00" level=info msg="zeroconf server listening on port 36123"
Mar 26 05:54:16 katarynka go-librespot[29478]: time="2026-03-26T05:54:16+01:00" level=debug msg="obtained new client token: AABoWRcRUCfkeGUurboPzTxUF1O046wIlQbNaLiPxjkyy9G2o+2L1bWXfQjP/QsrksgpDGh6FLhQHVMBkzC89ogI2zE94fd6H4O9Ta/D+tF8PTVEb+TyCG/HdJZlwBDjpoq1xhm37K7E6LteoZYrWU683uaxSiBSF+ojybKuVIt/Ovw4owCcv0NV6h6xPQV091+j+5YzvkVYtb7rR3tTToVI3P+afAR0EPYXgwFdQxCDm6Q2EMLhr5K2Hhk="
Mar 26 05:54:17 katarynka go-librespot[29478]: time="2026-03-26T05:54:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:17 katarynka go-librespot[29478]: time="2026-03-26T05:54:17+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:17 katarynka go-librespot[29478]: time="2026-03-26T05:54:17+01:00" level=debug msg="completed challenge"
Mar 26 05:54:17 katarynka go-librespot[29478]: time="2026-03-26T05:54:17+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 26 05:54:17 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:17 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:18 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:18 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:18 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:18 katarynka volumio[29127]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 26 05:54:18 katarynka volumio[29127]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 26 05:54:18 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:18 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:18 katarynka volumio[29127]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 26 05:54:18 katarynka volumio[29127]: info: MyVolumio login type: Token
Mar 26 05:54:18 katarynka volumio[29127]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 26 05:54:18 katarynka volumio[29127]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Mar 26 05:54:20 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:20 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:20 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:20 katarynka volumio[29127]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 26 05:54:20 katarynka volumio[29127]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 26 05:54:20 katarynka volumio[29127]: info: Streaming services startup
Mar 26 05:54:20 katarynka volumio[29127]: info: Starting Streaming Daemon
Mar 26 05:54:20 katarynka sudo[29487]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 26 05:54:20 katarynka sudo[29487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:20 katarynka volumio[29127]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 26 05:54:20 katarynka sudo[29487]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:20 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:20 katarynka volumio[29127]: error: Cannot start Volumio Streaming Daemon
Mar 26 05:54:20 katarynka volumio[29127]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 26 05:54:20 katarynka volumio[29127]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 26 05:54:20 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:20 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4.
Mar 26 05:54:20 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:21 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:21 katarynka go-librespot[29507]: go-librespot daemon starting...
Mar 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+01:00" level=debug msg="app state loaded"
Mar 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:21 katarynka volumio[29127]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Mar 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+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 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+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 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+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 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+01:00" level=info msg="zeroconf server listening on port 43131"
Mar 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+01:00" level=debug msg="obtained new client token: AAAWCGjc88LTgIF2IpKLHNJi09iRzreSxzE+1BAnPN4LmQbVrOujTihnY6Hn5SSNVRVONrKqdthwrSmSayONuMp3ez9YN33DPJZPkfXYno5Y2koDwIaUOiGGpEOnNMg/wN5Edqfljlq4eehOljSHi1lQ07Ica7qHfPEzIkcQgWGaNJOTaHnNQwChIlwW43fKLqQdcnlC3ftDc+ZjmBV+6jOKsPA2qhHmlOpfrzmUXohTGTYODbiTsx4c9Wo="
Mar 26 05:54:21 katarynka volumio[29127]: info: MyVolumio token set successfully
Mar 26 05:54:21 katarynka volumio[29127]: info: MYVOLUMIO: Adding device
Mar 26 05:54:21 katarynka volumio[29127]: info: MYVOLUMIO: Evaluating Server
Mar 26 05:54:21 katarynka go-librespot[29508]: time="2026-03-26T05:54:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:22 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:22 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:22 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:22 katarynka go-librespot[29508]: time="2026-03-26T05:54:22+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:22 katarynka go-librespot[29508]: time="2026-03-26T05:54:22+01:00" level=debug msg="completed challenge"
Mar 26 05:54:22 katarynka volumio[29127]: info: MyVolumio status changed
Mar 26 05:54:22 katarynka volumio[29127]: info: Streaming services startup
Mar 26 05:54:22 katarynka volumio[29127]: info: Starting Streaming Daemon
Mar 26 05:54:22 katarynka volumio[29127]: info: Removing browser output: myVolumio user plan is not superstar
Mar 26 05:54:22 katarynka volumio[29127]: info: Removing audio output:
Mar 26 05:54:22 katarynka volumio[29127]: info: Stoppping Tunnel 1
Mar 26 05:54:22 katarynka sudo[29537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 26 05:54:22 katarynka sudo[29537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:22 katarynka sudo[29539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Mar 26 05:54:22 katarynka sudo[29539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 26 05:54:22 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 26 05:54:22 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 26 05:54:22 katarynka sudo[29537]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:22 katarynka volumio[29127]: error: Cannot start Volumio Streaming Daemon
Mar 26 05:54:22 katarynka volumio[29127]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 26 05:54:22 katarynka volumio[29127]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 26 05:54:22 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 26 05:54:22 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 26 05:54:22 katarynka go-librespot[29508]: time="2026-03-26T05:54:22+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 26 05:54:22 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 26 05:54:22 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 26 05:54:22 katarynka sudo[29539]: pam_unix(sudo:session): session closed for user root
Mar 26 05:54:22 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:22 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:22 katarynka volumio[29127]: info: Remote SSH Stopped
Mar 26 05:54:22 katarynka volumio[29127]: info: Setting Geolocation for MyVolumio to eu10
Mar 26 05:54:22 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:22 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:22 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:23 katarynka volumio[29127]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 26 05:54:23 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:23 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:23 katarynka volumio[29127]: info: Updating MyVolumio device info
Mar 26 05:54:23 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:23 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:23 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:23 katarynka volumio[29127]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 26 05:54:24 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:24 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:24 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:25 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5.
Mar 26 05:54:25 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:25 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:25 katarynka go-librespot[29543]: go-librespot daemon starting...
Mar 26 05:54:25 katarynka go-librespot[29544]: time="2026-03-26T05:54:25+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:25 katarynka go-librespot[29544]: time="2026-03-26T05:54:25+01:00" level=debug msg="app state loaded"
Mar 26 05:54:25 katarynka go-librespot[29544]: time="2026-03-26T05:54:25+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:26 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:26 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:26 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+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 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+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 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+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 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+01:00" level=info msg="zeroconf server listening on port 38983"
Mar 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+01:00" level=debug msg="obtained new client token: AAAcov8fzACle+XrY8yxoGuAb/nD/NncqyjT1CCOasTh6HTxr/KQsNoGwIiYjxxQwCpPq4QsFlehRJP7R3NNe5NRYzkdh/+yrxZO2W2k4tgDgHnftyHF2vkYhcE9PZOz98AidO7KVJTvmloPPPR4u0Sy6vQkhEFm7IfQ5UzFny+HcSLucyj+gScATVKqKrxujD8wfAqXjeX8MRDXxJ4kKWGv7NX2T2f6hIdePOgea3mQOywD6r+1xOMcEbE="
Mar 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+01:00" level=debug msg="completed challenge"
Mar 26 05:54:26 katarynka go-librespot[29544]: time="2026-03-26T05:54:26+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 26 05:54:26 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:26 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:26 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:26 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:27 katarynka volumio[29127]: info: MYVOLUMIO: Adding device
Mar 26 05:54:27 katarynka volumio[29127]: info: MYVOLUMIO: Evaluating Server
Mar 26 05:54:28 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:28 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:28 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:28 katarynka volumio[29127]: info: Setting Geolocation for MyVolumio to eu6
Mar 26 05:54:28 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:28 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:28 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:28 katarynka volumio[29127]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Mar 26 05:54:29 katarynka volumio[29127]: info: Updating MyVolumio device info
Mar 26 05:54:29 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:29 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:29 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:29 katarynka volumio[29127]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Mar 26 05:54:29 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:29 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:29 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6.
Mar 26 05:54:29 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:29 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:29 katarynka go-librespot[29569]: go-librespot daemon starting...
Mar 26 05:54:29 katarynka go-librespot[29570]: time="2026-03-26T05:54:29+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:29 katarynka go-librespot[29570]: time="2026-03-26T05:54:29+01:00" level=debug msg="app state loaded"
Mar 26 05:54:29 katarynka go-librespot[29570]: time="2026-03-26T05:54:29+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:30 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:30 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:30 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+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 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+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 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+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 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+01:00" level=info msg="zeroconf server listening on port 46337"
Mar 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+01:00" level=debug msg="obtained new client token: AABAeWqmpTt8uIgT+i/uB7bE/jtl0K6wbeix6t4ZxWCHjN+epuRkaZd54HtMHbp20zx5Y+4auojqRP+x2Zep6hJ8WQexCiN/QxneC1tJ50T3fU2riQfFZ3dChqMB9a7etcbvhf4kgDcqkyNrR6eCVG+b6yVL6Sl9rsDJSJZm/8E8Odu2ea9T1U0N0RJn98ssPg/VemyatLm8eGkdZD5jL2tl4/E1BJ8usg1nqEmD6N2LzyMj2Wt5o8nr5eM="
Mar 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+01:00" level=debug msg="completed challenge"
Mar 26 05:54:30 katarynka go-librespot[29570]: time="2026-03-26T05:54:30+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 26 05:54:30 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:30 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:30 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 26 05:54:30 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:30 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 26 05:54:31 katarynka volumio-remote-updater[686]: Test mode disabled
Mar 26 05:54:31 katarynka volumio-remote-updater[686]: Alpha mode disabled
Mar 26 05:54:31 katarynka volumio-remote-updater[686]: Alpha legacy test mode disabled
Mar 26 05:54:31 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 26 05:54:31 katarynka volumio[29127]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for BLE notification issues
\n- Fix for mDNS/BCT
\n- Fix wireless init on Pi
\n- Fix raspi-config abuse on Pi
\n
\nNEW ADDITIONS
\n\n- Bluetooth party mode
\n- Update Tidal SDK
\n- Update Qobuz Connect SDK
\n
\n","title":"Update v4.103","updateavailable":true}
Mar 26 05:54:31 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 26 05:54:32 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:32 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:32 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:32 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:32 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:33 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 26 05:54:33 katarynka volumio[29127]: 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 26 05:54:33 katarynka volumio[29127]: info: CoreCommandRouter::volumioGetState
Mar 26 05:54:33 katarynka volumio[29127]: info: CorePlayQueue::getTrack 0
Mar 26 05:54:33 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7.
Mar 26 05:54:33 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:33 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:33 katarynka go-librespot[29598]: go-librespot daemon starting...
Mar 26 05:54:33 katarynka go-librespot[29599]: time="2026-03-26T05:54:33+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:33 katarynka go-librespot[29599]: time="2026-03-26T05:54:33+01:00" level=debug msg="app state loaded"
Mar 26 05:54:33 katarynka go-librespot[29599]: time="2026-03-26T05:54:33+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:34 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:34 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:34 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+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 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+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 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+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 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+01:00" level=info msg="zeroconf server listening on port 46087"
Mar 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+01:00" level=debug msg="obtained new client token: AACSjcti+ClEw7f9C7b50sllzigXB2d8qG/+6gdUoz44rrcJ7Ec+cC4WHrAzDAw+itccASAATmNDDnq0gxCCORxqZOol44cODkNeM4W1PyEekSKxHsa9lt5OYyftD31LWQ7hn+aZa9KvBPuCaQz2BmWK1Rm/pTIvA7M4Cvo26ZU5XMlfFxB8paJG5N0hFQE3fPVbcTNdTWOo2JjGvEnfBiQNcd0WAs/D4KXKoYQBzQtm+dqSp9IF+7cp8Ws="
Mar 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+01:00" level=debug msg="completed challenge"
Mar 26 05:54:34 katarynka go-librespot[29599]: time="2026-03-26T05:54:34+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 26 05:54:34 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:34 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:35 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:35 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:36 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:36 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:36 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:37 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8.
Mar 26 05:54:37 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:37 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:37 katarynka go-librespot[29606]: go-librespot daemon starting...
Mar 26 05:54:37 katarynka go-librespot[29607]: time="2026-03-26T05:54:37+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:37 katarynka go-librespot[29607]: time="2026-03-26T05:54:37+01:00" level=debug msg="app state loaded"
Mar 26 05:54:37 katarynka go-librespot[29607]: time="2026-03-26T05:54:37+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:38 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:38 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:38 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+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 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+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 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+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 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+01:00" level=info msg="zeroconf server listening on port 33367"
Mar 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+01:00" level=debug msg="obtained new client token: AABgSn/86vqxunT7DT52a2n4J83NSKUxwwF4emFmTer/cYKaK1BGh5oLi+5rH5odQq+lOfTPCFwBb5OBVAKFCfcOo+p6Aualvju07sLC5plWagZVs4sf+vuXtsuN7G3KUcLWgFfASygujXlj7+0j+Mk4Es9iA8967kUmHAkBwNPGQRIP+3UI0O66FIU7o+4kCqMjV8N2VzLt0TQQz95maiRd+FRstVELpJLLHHCrl4ven4Qd7Gn9k/Ym77A="
Mar 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+01:00" level=debug msg="completed challenge"
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 26 05:54:38 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 26 05:54:38 katarynka go-librespot[29607]: time="2026-03-26T05:54:38+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 26 05:54:38 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:38 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:38 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:38 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:40 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:40 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:40 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:40 katarynka volumio[29127]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Mar 26 05:54:40 katarynka volumio[29127]: info: Completed starting MyVolumio Plugin
Mar 26 05:54:40 katarynka volumio[29127]: [Metrics] CommandRouter: 53s 835.08ms
Mar 26 05:54:40 katarynka volumio[29127]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 26 05:54:40 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 26 05:54:40 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 26 05:54:40 katarynka volumio[29127]: info: CoreCommandRouter::Close All Modals sent
Mar 26 05:54:40 katarynka volumio[29127]: info: CoreCommandRouter::Close All Modals sent
Mar 26 05:54:41 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 26 05:54:41 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 26 05:54:41 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 26 05:54:41 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:41 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:41 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9.
Mar 26 05:54:41 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:41 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:41 katarynka go-librespot[29632]: go-librespot daemon starting...
Mar 26 05:54:41 katarynka go-librespot[29633]: time="2026-03-26T05:54:41+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:41 katarynka go-librespot[29633]: time="2026-03-26T05:54:41+01:00" level=debug msg="app state loaded"
Mar 26 05:54:41 katarynka go-librespot[29633]: time="2026-03-26T05:54:41+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:42 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:42 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:42 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+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 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+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 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+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 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+01:00" level=info msg="zeroconf server listening on port 43739"
Mar 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+01:00" level=debug msg="obtained new client token: AACxrhPglAmciIKR1PiyiPqo/T8ko9bVtp62vRLxNMiUawDfSSbJ/6U5G/ihmnDisLej43JO1yWXrpMUdBfqZMsGc5szqi9Dz5CUgQnSEX7SAO1f79xVbBaqIZeP5rVVGodE2a6RSziyG+wszXp10OuWZho3puFHc4e4hf8s4uPk/XwcjaAN6ai3Jw+/cWqvlB72WiCE5tbHIjGoh8Z1QncdhIa1XJFnpAmydPbKrXiBYrISSFKVc6pSBHo="
Mar 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+01:00" level=debug msg="completed challenge"
Mar 26 05:54:42 katarynka go-librespot[29633]: time="2026-03-26T05:54:42+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 26 05:54:42 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:42 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:44 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:44 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:44 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:44 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:44 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:45 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10.
Mar 26 05:54:45 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:45 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:45 katarynka go-librespot[29641]: go-librespot daemon starting...
Mar 26 05:54:45 katarynka go-librespot[29642]: time="2026-03-26T05:54:45+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:45 katarynka go-librespot[29642]: time="2026-03-26T05:54:45+01:00" level=debug msg="app state loaded"
Mar 26 05:54:45 katarynka go-librespot[29642]: time="2026-03-26T05:54:45+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:46 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:46 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:46 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+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 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+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 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+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 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+01:00" level=info msg="zeroconf server listening on port 45621"
Mar 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+01:00" level=debug msg="obtained new client token: AADCWOW3aRJ6xKdNw+SnvvStPY28IQGbWHZPeazqoiMpP5ourJcIT/2Acjh+aTg1SvMvnovgc8wfN43BkYXGn4mzMLFBrRk04pwfl/HPS2WzdBEef+nzj+oy9B0lp0a+RBQVRNekEO+aIzQA4gbCzB4C3PkK73N8BuCt8ZtAfOSJKzJXXZj60+K3VD/0JuSjXr/kszjFzIOSCJlqW9OlE3mWUsDLWrdyoXMdiTEPTAS+5U8jTTzQHkLheTM="
Mar 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+01:00" level=debug msg="completed challenge"
Mar 26 05:54:46 katarynka go-librespot[29642]: time="2026-03-26T05:54:46+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 26 05:54:46 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:46 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:47 katarynka volumio[29127]: info: BOOT COMPLETED
Mar 26 05:54:47 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:47 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:48 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:48 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:48 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:49 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11.
Mar 26 05:54:49 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:49 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:49 katarynka go-librespot[29649]: go-librespot daemon starting...
Mar 26 05:54:49 katarynka go-librespot[29650]: time="2026-03-26T05:54:49+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:49 katarynka go-librespot[29650]: time="2026-03-26T05:54:49+01:00" level=debug msg="app state loaded"
Mar 26 05:54:49 katarynka go-librespot[29650]: time="2026-03-26T05:54:49+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:50 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:50 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:50 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+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 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+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 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+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 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+01:00" level=info msg="zeroconf server listening on port 37213"
Mar 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+01:00" level=debug msg="obtained new client token: AAC/vuMMh5Pkd8TBcjwUfsvrdkHxuxd0PEU94GZo+WT08xJEAckRVA2LczDsiLDloTBjd0HsIt8GuzSseu/ax+KyTkPhtaPkllWw+hE2Qc7oxwq7ZdfxtN6WbhqFPzlOlrYlHMT3SgKSVgD6wgzMKz4ckXz2HSNlbSdjwG8QzFNvqW/71ZWYx9/c6GcYok7z/hVnsg9voVYTnmEIl5THasECeHZOugwbUNh53F/czcBlGtd/BPxSnZmt6uM="
Mar 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+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 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Mar 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+01:00" level=debug msg="completed challenge"
Mar 26 05:54:50 katarynka go-librespot[29650]: time="2026-03-26T05:54:50+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 26 05:54:50 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:50 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:50 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:50 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:52 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:52 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:52 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:53 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12.
Mar 26 05:54:53 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:53 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:53 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:53 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:53 katarynka go-librespot[29674]: go-librespot daemon starting...
Mar 26 05:54:53 katarynka go-librespot[29675]: time="2026-03-26T05:54:53+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:53 katarynka go-librespot[29675]: time="2026-03-26T05:54:53+01:00" level=debug msg="app state loaded"
Mar 26 05:54:53 katarynka go-librespot[29675]: time="2026-03-26T05:54:53+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:54 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:54 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:54 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+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 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+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 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+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 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+01:00" level=info msg="zeroconf server listening on port 36821"
Mar 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+01:00" level=debug msg="obtained new client token: AAC6ikDoG0uzqIoB9lCz3vmeQ2GvOrrDYcyJ+vTp/7sr5N4FYmRAhLAy4MB1yhujcqR61DtIyjl/aoadpliCzJjJvaJ3pOzxNqrlTRJTTKq9hlK66y4ZrAzawh7qYIm+fLxTLo1Q/uXKTO/rHnJk5Y7nVkW7JD5zgcJv72vdRDDTiKMH32kvX9Ts1RbRtWadBxfAtwTURwTwHdlI0gYbJirio1s5yCxWgVc2hJP2khyXWAXOeeHeETRHDwQ="
Mar 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+01:00" level=debug msg="completed challenge"
Mar 26 05:54:54 katarynka go-librespot[29675]: time="2026-03-26T05:54:54+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 26 05:54:54 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:54 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:56 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:56 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:56 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:56 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:56 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:54:57 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13.
Mar 26 05:54:57 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:57 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:54:57 katarynka go-librespot[29683]: go-librespot daemon starting...
Mar 26 05:54:57 katarynka go-librespot[29684]: time="2026-03-26T05:54:57+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:54:57 katarynka go-librespot[29684]: time="2026-03-26T05:54:57+01:00" level=debug msg="app state loaded"
Mar 26 05:54:57 katarynka go-librespot[29684]: time="2026-03-26T05:54:57+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:54:58 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:54:58 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:54:58 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+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 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+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 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+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 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+01:00" level=info msg="zeroconf server listening on port 32787"
Mar 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+01:00" level=debug msg="obtained new client token: AABStwouTHipEmDYiXiiLy7VuOua5naJMCPmPCm1PfFWormxPpIOAhiB7LmCuOjP/q+EbXx5CGL2DAN2nIQmUOdY5+9V+Q3VYTvOazTIbSaIW6Wp4W7otU69GbmhXf1/gjkcp/9oM5yj9fKA+/5lSsIMVV662nIstVFIQPnw05Q8ILtP9mnBA7gG5JZDNnFW1Bnb3jW6EkUbuYYygtZd5CwQiBHBLCJZ+kjdZdxmJMs8GSckK5NhVue5I9c="
Mar 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+01:00" level=debug msg="completed keyexchange"
Mar 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+01:00" level=debug msg="completed challenge"
Mar 26 05:54:58 katarynka go-librespot[29684]: time="2026-03-26T05:54:58+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 26 05:54:58 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:54:58 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:54:59 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:54:59 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:00 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:00 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:00 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:01 katarynka volumio[29127]: info: UPDATER: Scheduling automatic update
Mar 26 05:55:01 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Mar 26 05:55:01 katarynka volumio[29127]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Mar 26 05:55:01 katarynka volumio[29127]: info: UPDATER: Auto update will take place at: Fri Mar 27 2026 05:15:07 GMT+0100 (Central European Standard Time)
Mar 26 05:55:01 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14.
Mar 26 05:55:01 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:01 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:01 katarynka go-librespot[29705]: go-librespot daemon starting...
Mar 26 05:55:01 katarynka go-librespot[29706]: time="2026-03-26T05:55:01+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:01 katarynka go-librespot[29706]: time="2026-03-26T05:55:01+01:00" level=debug msg="app state loaded"
Mar 26 05:55:01 katarynka go-librespot[29706]: time="2026-03-26T05:55:01+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:02 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:02 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:02 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+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 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+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 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+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 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+01:00" level=info msg="zeroconf server listening on port 38899"
Mar 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+01:00" level=debug msg="obtained new client token: AACxkSf8lldRdwu3bkErFWycnXIvN4a8v7VgtqiwweHo92c6Sms4r56QD2cugJPb+G/X7B09acT8D2dfKuGQ6rV1/+m4XngkYLhaSqkNAytguvwdzrNOfnS8WDPWTSEg8ouTiejXq+ZbZ67S8+3i4WBWZlz+ewHvC2vBUGj45LySl/CF9HpYfwUQAaKv7N96i5XCBDtTdGQ28etqO065o1sUfaLXH6kcHLJNUHaM6qnmTY59btvHqqV4m/E="
Mar 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+01:00" level=debug msg="completed challenge"
Mar 26 05:55:02 katarynka go-librespot[29706]: time="2026-03-26T05:55:02+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 26 05:55:02 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:02 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:02 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:02 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:04 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:04 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:04 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:05 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15.
Mar 26 05:55:05 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:05 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:05 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:05 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:05 katarynka go-librespot[29714]: go-librespot daemon starting...
Mar 26 05:55:05 katarynka go-librespot[29715]: time="2026-03-26T05:55:05+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:05 katarynka go-librespot[29715]: time="2026-03-26T05:55:05+01:00" level=debug msg="app state loaded"
Mar 26 05:55:05 katarynka go-librespot[29715]: time="2026-03-26T05:55:05+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:06 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:06 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:06 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+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 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+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 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+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 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+01:00" level=info msg="zeroconf server listening on port 38209"
Mar 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+01:00" level=debug msg="obtained new client token: AAAFfJAidOvMcNJxKnL1jzi32xkht43W99nIljghrhPxtSRwFYCAH1Be/5H03eqwXoLGX/QGwMf20dHqqOJ9BUvNVZsL1XFGInjH3BU2oYf6eIGkhY6etqTY1CiXwLeZhpr2hghReZWATOQ9zlj6LFLMHTCU2ESUjVDIuPwi0PNawo/UCBH2sEywZsizHQDaak+oVjWqapSNsY5U6DgdLz971AAgas3lMmLuwA/o+6nn5j69y2eANg+WSsM="
Mar 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+01:00" level=debug msg="completed challenge"
Mar 26 05:55:06 katarynka go-librespot[29715]: time="2026-03-26T05:55:06+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 26 05:55:06 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:06 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:08 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:08 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:08 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:08 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:08 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:09 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16.
Mar 26 05:55:09 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:09 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:09 katarynka go-librespot[29722]: go-librespot daemon starting...
Mar 26 05:55:09 katarynka go-librespot[29723]: time="2026-03-26T05:55:09+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:09 katarynka go-librespot[29723]: time="2026-03-26T05:55:09+01:00" level=debug msg="app state loaded"
Mar 26 05:55:09 katarynka go-librespot[29723]: time="2026-03-26T05:55:09+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:10 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:10 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:10 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+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 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+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 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+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 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+01:00" level=info msg="zeroconf server listening on port 40831"
Mar 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+01:00" level=debug msg="obtained new client token: AACT5edWB5K+Vsc/cZ0zjlB13WeX5uXCjDureY0cSPqACk+r9mfg+Zi4dGnRODhzjqHgGYjhXcBSYL1AmFzZCe0vhRD8kTTpBXwE8Y9i8HLl3wk2XjKhTQlazgn4doiJdYxdx4+WxGc7bQGxsAE4SB5Xec0Zx8Azj0nhY6BFr6cfg/MvCXDgeUJ5FkYE9SjdXnN9XWK7AOO+1ZCIz7ECqvunChXF0WTqPdYkUC0UKCiF0dF1yCwhSs+EqJA="
Mar 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+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 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Mar 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+01:00" level=debug msg="completed challenge"
Mar 26 05:55:10 katarynka go-librespot[29723]: time="2026-03-26T05:55:10+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 26 05:55:10 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:10 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:11 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:11 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:12 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:12 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:12 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:13 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17.
Mar 26 05:55:13 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:13 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:13 katarynka go-librespot[29747]: go-librespot daemon starting...
Mar 26 05:55:13 katarynka go-librespot[29748]: time="2026-03-26T05:55:13+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:13 katarynka go-librespot[29748]: time="2026-03-26T05:55:13+01:00" level=debug msg="app state loaded"
Mar 26 05:55:13 katarynka go-librespot[29748]: time="2026-03-26T05:55:13+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:14 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:14 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:14 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+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 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+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 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+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 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+01:00" level=info msg="zeroconf server listening on port 35101"
Mar 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+01:00" level=debug msg="obtained new client token: AADg+0AbiPTrQbV5JO8ccK/lMe3CIpm4YWYvsCD30+x9lDPMkeW9WzbQttIc1KvchgysylrzI4wS9gaz7w20fefLW33E6Vf/eaTiSZH473j8zaeY7oYW8ZgvMYENifN8lBd2xt2Bn6Q7O/wmsToYHaWCFLn2PuAY3+hNAyzOYVnA9ATi+VsYZwu/sJKWu89KwoOSu6GvBZMyHeegrEkias7EeZTIbYMWbkvrb6r7/NAdlPtiznv3O4WyZUA="
Mar 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+01:00" level=debug msg="completed challenge"
Mar 26 05:55:14 katarynka go-librespot[29748]: time="2026-03-26T05:55:14+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 26 05:55:14 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:14 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:14 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:14 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:16 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:16 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:16 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:17 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18.
Mar 26 05:55:17 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:17 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:17 katarynka go-librespot[29755]: go-librespot daemon starting...
Mar 26 05:55:17 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:17 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:17 katarynka go-librespot[29756]: time="2026-03-26T05:55:17+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:17 katarynka go-librespot[29756]: time="2026-03-26T05:55:17+01:00" level=debug msg="app state loaded"
Mar 26 05:55:17 katarynka go-librespot[29756]: time="2026-03-26T05:55:17+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:18 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:18 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:18 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+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 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+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 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+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 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+01:00" level=info msg="zeroconf server listening on port 42213"
Mar 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+01:00" level=debug msg="obtained new client token: AACztVrVxjcpMX3TbLRAJMeGMMmISAwhCn0PK7VqFEGdpHjprB14gkpJWDdAasgLsjntOXmZ2E1+3zbUHCWlPcYmOyVhfM8uw6VfnVRk+JtPOagUvSYwc22R3Gqmt0SoP2HSI2CUTrMC9xak/37bscQ0Ock1QN7pYjT87lh1e0RNTCdUtsqwdgU7IdT+DD57Nr6XrFQxQ5kD82ZS9IXpp0d7Rv30U6i5cQMPWARDqr7LETBjZweML8wSNgE="
Mar 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+01:00" level=debug msg="completed challenge"
Mar 26 05:55:18 katarynka go-librespot[29756]: time="2026-03-26T05:55:18+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 26 05:55:18 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:18 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:20 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:20 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:20 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:20 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:20 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:21 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19.
Mar 26 05:55:21 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:21 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:21 katarynka go-librespot[29777]: go-librespot daemon starting...
Mar 26 05:55:21 katarynka go-librespot[29778]: time="2026-03-26T05:55:21+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:21 katarynka go-librespot[29778]: time="2026-03-26T05:55:21+01:00" level=debug msg="app state loaded"
Mar 26 05:55:21 katarynka go-librespot[29778]: time="2026-03-26T05:55:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:22 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:22 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:22 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+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 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+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 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+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 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+01:00" level=info msg="zeroconf server listening on port 36819"
Mar 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+01:00" level=debug msg="obtained new client token: AAAbx3OqmmuGTvlGNkxUN2JGSRosqeHMvGtr7G/x70GX9aO8f7GuE7Lmk0/ojl5xKLzhqDH3+FTcn1rGyUQv8h4zVBOgDKLIV7PHI8MLOesuexTyHwMdT8U2J/S1ccuevNyEi8xGxfdRZ9R1Gnu30JwwIALtSM8FkyIsGvgZqIorLjD5zwKofUxu1119bMNSq3Vhq/qR1QZFQRXPBYHa+McIgseqQrrT/u492Ad5RTWf2I62TCqvFe3n+9A="
Mar 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+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 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Mar 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+01:00" level=debug msg="completed challenge"
Mar 26 05:55:22 katarynka go-librespot[29778]: time="2026-03-26T05:55:22+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 26 05:55:22 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:22 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:23 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:23 katarynka volumio[29127]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:24 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:24 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:24 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:25 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20.
Mar 26 05:55:25 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:25 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:25 katarynka go-librespot[29786]: go-librespot daemon starting...
Mar 26 05:55:25 katarynka go-librespot[29787]: time="2026-03-26T05:55:25+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:25 katarynka go-librespot[29787]: time="2026-03-26T05:55:25+01:00" level=debug msg="app state loaded"
Mar 26 05:55:25 katarynka go-librespot[29787]: time="2026-03-26T05:55:25+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:26 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:26 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:26 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+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 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+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 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+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 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+01:00" level=info msg="zeroconf server listening on port 39597"
Mar 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+01:00" level=debug msg="obtained new client token: AACQemKXmCKLear+goWsxuDJ0FcYsHMeLnZ33WhV+4bIOXrD7/PYfk8f4yenetygAI6Xy5FyrHucApZpc8e8Pb5m9tN06xE6vEkZlm7tt54ZYOd+ENlHGEQuxmpVb65fUZlsCS/HaB6M5JTtkJLFdA/z2RRIeEtCqpK2SpgvkfIOfntVmJqkGLy/Xh6E5eB5wybYmPenXF7hZJlLW+WBBS+TYLjUuEaK4E+bSUUZvyE0rMuxhkt/N+JbS0o="
Mar 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:55:26 katarynka volumio[29127]: info: Initializing connection to go-librespot Websocket
Mar 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+01:00" level=debug msg="new websocket client"
Mar 26 05:55:26 katarynka volumio[29127]: info: Connection to go-librespot Websocket established
Mar 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:26 katarynka go-librespot[29787]: time="2026-03-26T05:55:26+01:00" level=debug msg="completed challenge"
Mar 26 05:55:27 katarynka go-librespot[29787]: time="2026-03-26T05:55:27+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 26 05:55:27 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:27 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:27 katarynka volumio[29127]: info: Connection to go-librespot Websocket closed
Mar 26 05:55:28 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:28 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:28 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:29 katarynka volumio[29127]: info: Getting Spotify volume
Mar 26 05:55:29 katarynka volumio[29127]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 26 05:55:29 katarynka volumio[29127]: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 26 05:55:29 katarynka volumio[29127]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Mar 26 05:55:29 katarynka volumio[29127]: errno: -111,
Mar 26 05:55:29 katarynka volumio[29127]: code: 'ECONNREFUSED',
Mar 26 05:55:29 katarynka volumio[29127]: syscall: 'connect',
Mar 26 05:55:29 katarynka volumio[29127]: address: '127.0.0.1',
Mar 26 05:55:29 katarynka volumio[29127]: port: 9879,
Mar 26 05:55:29 katarynka volumio[29127]: response: undefined
Mar 26 05:55:29 katarynka volumio[29127]: }
Mar 26 05:55:29 katarynka volumio[29127]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 26 05:55:30 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name
Mar 26 05:55:30 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection
Mar 26 05:55:30 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0
Mar 26 05:55:30 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21.
Mar 26 05:55:30 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:30 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 26 05:55:30 katarynka go-librespot[29806]: go-librespot daemon starting...
Mar 26 05:55:30 katarynka go-librespot[29807]: time="2026-03-26T05:55:30+01:00" level=info msg="running go-librespot 0.4.0"
Mar 26 05:55:30 katarynka go-librespot[29807]: time="2026-03-26T05:55:30+01:00" level=debug msg="app state loaded"
Mar 26 05:55:30 katarynka go-librespot[29807]: time="2026-03-26T05:55:30+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 26 05:55:30 katarynka go-librespot[29807]: time="2026-03-26T05:55:30+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 26 05:55:30 katarynka go-librespot[29807]: time="2026-03-26T05:55:30+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 26 05:55:30 katarynka go-librespot[29807]: time="2026-03-26T05:55:30+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 26 05:55:31 katarynka go-librespot[29807]: time="2026-03-26T05:55:31+01:00" level=info msg="zeroconf server listening on port 44553"
Mar 26 05:55:31 katarynka go-librespot[29807]: time="2026-03-26T05:55:31+01:00" level=debug msg="obtained new client token: AAByDQ2tQSCBp8lEDw1Q0ABuvZ1dzPXWW+iYQqOf8wkqL8x9zD/ksKpjkbS8eV2kRKGB6VdXaz8mCcGPVd3edwzHYJ0DC+H2vmh/Txcnkq0C6fp2z53Ftr9f13Xm8lCNWa9tkMUJye8rMLZks6m/01hmUfCo3g9+HphjTLSAvVJwklkbiYHRWG5BoIrSvcNHakocIyC///IiW5CTH6hZG/7NujugMI8IKxN4KSWFx7mLsO3bzzbCSY+W"
Mar 26 05:55:31 katarynka go-librespot[29807]: time="2026-03-26T05:55:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 26 05:55:31 katarynka go-librespot[29807]: time="2026-03-26T05:55:31+01:00" level=debug msg="completed keyexchange"
Mar 26 05:55:31 katarynka go-librespot[29807]: time="2026-03-26T05:55:31+01:00" level=debug msg="completed challenge"
Mar 26 05:55:31 katarynka go-librespot[29807]: time="2026-03-26T05:55:31+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 26 05:55:31 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 26 05:55:31 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 26 05:55:31 katarynka sudo[29833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 05:54'
Mar 26 05:55:31 katarynka sudo[29833]: 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"