Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+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 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+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 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=info msg="zeroconf server listening on port 46583"
Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=debug msg="obtained new client token: AADe2sZFQeynJfQP1dHOSG0WsdiJG9NnsL23AGrQ2ZhNNAXL/f06MpkWQdwjtYfMQG/pT5/Hs3/+ql+OHaiPD07z9gQTFrZ7ArmhNILn/xya6qAd1+5EHQrNOQfNqGxZv5GgXau0DHK8mSSVL3FXfV9/bzzAvg75twfledROZPkrN9CYM5Ka2bZ6QuFp4NYaTcD/eEREbUh+XPK4X+Jf4oZeL/AuJHMUkIkMRrsidmp8lbAOx9Rk"
Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=debug msg="completed challenge"
Mar 25 19:25:00 volumiokueche go-librespot[15752]: time="2026-03-25T19:25:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:25:00 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:00 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin multiroom to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin qobuzconnect to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 25 19:25:01 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:01 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:01 volumiokueche volumio[15378]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 25 19:25:01 volumiokueche volumio[15378]: info: MyVolumio login type: Token
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 25 19:25:01 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Mar 25 19:25:02 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Mar 25 19:25:02 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Mar 25 19:25:02 volumiokueche volumio[15378]: info: Streaming services startup
Mar 25 19:25:02 volumiokueche volumio[15378]: info: Starting Streaming Daemon
Mar 25 19:25:02 volumiokueche sudo[15760]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 25 19:25:02 volumiokueche sudo[15760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:02 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Mar 25 19:25:02 volumiokueche sudo[15760]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:02 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:02 volumiokueche volumio[15378]: error: Cannot start Volumio Streaming Daemon
Mar 25 19:25:02 volumiokueche volumio[15378]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 25 19:25:02 volumiokueche volumio[15378]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 25 19:25:02 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:02 volumiokueche volumio[15378]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Mar 25 19:25:03 volumiokueche volumio[15378]: info: MyVolumio token set successfully
Mar 25 19:25:03 volumiokueche volumio[15378]: info: MYVOLUMIO: Adding device
Mar 25 19:25:03 volumiokueche volumio[15378]: info: MYVOLUMIO: Evaluating Server
Mar 25 19:25:03 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 73.
Mar 25 19:25:03 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:03 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:03 volumiokueche go-librespot[15769]: go-librespot daemon starting...
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=debug msg="app state loaded"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+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 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+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 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=info msg="zeroconf server listening on port 42545"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=debug msg="obtained new client token: AAAvHhNcO0gniHaANKlKw3pwPer8vM8DR8RTmhp0QzUUEMNDQ+zH53kh+qmu3OdkcnnIvjYSsmQV54u2P54Gz9Bp8fR4ZRm0v3mwhEUS/19mvywhGlC1yUr5bcjk4MhwuLFNIiDLCV86afuiW10cpSI7eE8ZtoPwvGRgFikQVN3TKsZYULDerhm3cK3odw/DHaQniYjtDM+loXgnwrEXLwLZHmFumznN5tKmc9v6MIrCvSA/StNe/Cg="
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+01:00" level=debug msg="completed challenge"
Mar 25 19:25:03 volumiokueche go-librespot[15770]: time="2026-03-25T19:25:03+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 25 19:25:03 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:03 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:05 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:05 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:06 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 74.
Mar 25 19:25:06 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:06 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:06 volumiokueche go-librespot[15792]: go-librespot daemon starting...
Mar 25 19:25:06 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:06+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:06 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:06+01:00" level=debug msg="app state loaded"
Mar 25 19:25:06 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:06+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+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 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+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 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+01:00" level=info msg="zeroconf server listening on port 44203"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+01:00" level=debug msg="obtained new client token: AABAKmWBFvlCaD9VED4bVUtw8FA0FkELb7GcqxT0dlI3SMh+ER0EPXUExPkrticmckwWZayX0LU6Tx65Uby1JA82oLrLl28lNtt+FW2XDuI6iM6N45UuFUxtIt4xe4kgDKyemtzIydnNZCBM8mJMDIYwR1rAAgaV2272i6APgF10HH9vZfDv5BbTf09cKnh9VDKbLLI5AKkPz1Cw+ExkJ8xhljmG3DIbsiv5ltf6NMH3/Yq/E/NO"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+01:00" level=debug msg="completed challenge"
Mar 25 19:25:07 volumiokueche go-librespot[15793]: time="2026-03-25T19:25:07+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 25 19:25:07 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:07 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:07 volumiokueche volumio[15378]: info: MyVolumio Plan changed: premium
Mar 25 19:25:07 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Removing browser output: myVolumio user plan is not superstar
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Removing audio output:
Mar 25 19:25:07 volumiokueche volumio[15378]: info: MYVOLUMIO: Adding device
Mar 25 19:25:07 volumiokueche volumio[15378]: info: MYVOLUMIO: Evaluating Server
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Remote config written successfully
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Starting Tunnel 1
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Starting Tunnel Connection Checker
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Completed starting MyVolumio Plugin
Mar 25 19:25:07 volumiokueche volumio[15378]: info: MYVolumio Device enabled
Mar 25 19:25:07 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Mar 25 19:25:07 volumiokueche volumio[15378]: info: MyVolumio status changed
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Streaming services startup
Mar 25 19:25:07 volumiokueche volumio[15378]: info: Starting Streaming Daemon
Mar 25 19:25:07 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Mar 25 19:25:07 volumiokueche sudo[15837]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 25 19:25:07 volumiokueche sudo[15837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:07 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Mar 25 19:25:08 volumiokueche sudo[15837]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:08 volumiokueche volumio[15378]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Mar 25 19:25:08 volumiokueche sudo[15841]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Mar 25 19:25:08 volumiokueche sudo[15841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:08 volumiokueche sudo[15841]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:08 volumiokueche volumio[15378]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Mar 25 19:25:08 volumiokueche volumio[15378]: info: MRS: MultiRoom plugin initialized
Mar 25 19:25:08 volumiokueche volumio[15378]: info: MRS: STOPPING SNAPCLIENT
Mar 25 19:25:08 volumiokueche volumio[15378]: info: MRS: Snap server stop
Mar 25 19:25:08 volumiokueche volumio[15378]: info: MRS: STOPPING volumioStreaming
Mar 25 19:25:08 volumiokueche sudo[15858]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Mar 25 19:25:08 volumiokueche sudo[15858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:08 volumiokueche sudo[15860]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Mar 25 19:25:08 volumiokueche sudo[15860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Mar 25 19:25:08 volumiokueche sudo[15863]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Mar 25 19:25:08 volumiokueche sudo[15863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Mar 25 19:25:08 volumiokueche sudo[15866]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Mar 25 19:25:08 volumiokueche sudo[15866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:08 volumiokueche sudo[15866]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Mar 25 19:25:08 volumiokueche sudo[15858]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:08 volumiokueche sudo[15863]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:08 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Mar 25 19:25:09 volumiokueche sudo[15860]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Preparing to generate the ALSA configuration file
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:09 volumiokueche volumio[15378]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Reading ALSA contributions from plugins.
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: Removed streaming files
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: volumioStreaming STOPPED
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: SNAPSERVER STOPPED
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: SNAPCLIENT STOPPED
Mar 25 19:25:09 volumiokueche volumio[15378]: error: Cannot start Volumio Streaming Daemon
Mar 25 19:25:09 volumiokueche volumio[15378]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 25 19:25:09 volumiokueche volumio[15378]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Asound.conf file written
Mar 25 19:25:09 volumiokueche sudo[15871]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Mar 25 19:25:09 volumiokueche sudo[15871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:09 volumiokueche sudo[15871]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:09 volumiokueche volumio[15378]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Output device has changed, restarting MPD
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Output device has changed, restarting Shairport Sync
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:09 volumiokueche sudo[15877]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 25 19:25:09 volumiokueche sudo[15877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:09 volumiokueche sudo[15877]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:09 volumiokueche sudo[15880]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche sudo[15880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: Pushing multiroomSync output for this device
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: Pushing multiroomSync output
Mar 25 19:25:09 volumiokueche systemd[1]: Stopping mpd.service - Music Player Daemon...
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding audio output:
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding audio output:
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding METAVOLUMIO REST API Endpoints
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Mar 25 19:25:09 volumiokueche volumio[15378]: error: Hi Res Audio Failed Login: Missing Login Data
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding HIGHRESAUDIO REST API Endpoints
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Mar 25 19:25:09 volumiokueche systemd[1]: mpd.service: Deactivated successfully.
Mar 25 19:25:09 volumiokueche systemd[1]: Stopped mpd.service - Music Player Daemon.
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting
Mar 25 19:25:09 volumiokueche systemd[1]: mpd.service: Consumed 2.022s CPU time.
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Mar 25 19:25:09 volumiokueche volumio[15378]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting
Mar 25 19:25:09 volumiokueche systemd[1]: mpd.socket: Deactivated successfully.
Mar 25 19:25:09 volumiokueche systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Mar 25 19:25:09 volumiokueche systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding TIDAL REST API Endpoints
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Stopping AccessToken refresher cron for QOBUZ
Mar 25 19:25:09 volumiokueche volumio[15378]: info: AccessToken refresher cron started for QOBUZ
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Adding QOBUZ REST API Endpoints
Mar 25 19:25:09 volumiokueche systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Mar 25 19:25:09 volumiokueche systemd[1]: Starting mpd.service - Music Player Daemon...
Mar 25 19:25:09 volumiokueche volumio[15378]: info: MPD Permissions set
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]:  [Metrics]  CommandRouter:  26s 563.76ms 
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::Close All Modals sent
Mar 25 19:25:09 volumiokueche volumio[15378]: info: CoreCommandRouter::Close All Modals sent
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Starting Shairport Sync
Mar 25 19:25:09 volumiokueche sudo[15901]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 25 19:25:09 volumiokueche sudo[15901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:09 volumiokueche systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 25 19:25:09 volumiokueche systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 25 19:25:09 volumiokueche systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 25 19:25:09 volumiokueche systemd[1]: shairport-sync.service: Consumed 1.615s CPU time.
Mar 25 19:25:09 volumiokueche sudo[15892]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 25 19:25:09 volumiokueche sudo[15892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 25 19:25:09 volumiokueche sudo[15892]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:09 volumiokueche systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 25 19:25:09 volumiokueche sudo[15901]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:09 volumiokueche volumio[15378]: info: Shairport-Sync Started
Mar 25 19:25:10 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 25 19:25:10 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 25 19:25:10 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 75.
Mar 25 19:25:10 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:10 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 25 19:25:10 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:10 volumiokueche go-librespot[15923]: go-librespot daemon starting...
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=debug msg="app state loaded"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25: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 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25: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 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25: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 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=info msg="zeroconf server listening on port 43715"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=debug msg="obtained new client token: AABMdTjQu6mJjeNAyD/G1+oKPASbdJF+3DnHczvEQ3IGKRDH/+YCYVSkiefjDsd4t5gMu5Ni1WP9mflG3r1B042qHThRpIESUvfJ4EZP9gzWbCc9gGXT4hJF+qdbgO14J8WvqZZkn8slLut47NaoRDrGmDa54ARHmeKRgq+WDARxlKKuaIKIp5mvh/YoZiDXnPJAfykM50ZDTrHbSyEHhdi6aSzOHdMv15ASHtyfkpSnhBx3b2szM88="
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25:10+01:00" level=debug msg="completed challenge"
Mar 25 19:25:10 volumiokueche go-librespot[15924]: time="2026-03-25T19:25: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 25 19:25:10 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:10 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:10 volumiokueche mpd[15905]: 2026-03-25T19:25:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 25 19:25:10 volumiokueche systemd[1]: Started mpd.service - Music Player Daemon.
Mar 25 19:25:10 volumiokueche sudo[15880]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:10 volumiokueche volumio[15378]: error: updateQueue error: null
Mar 25 19:25:12 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:12 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:12 volumiokueche sudo[15939]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Mar 25 19:25:12 volumiokueche sudo[15939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:12 volumiokueche autossh[15347]: received signal to exit (15)
Mar 25 19:25:12 volumiokueche systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel...
Mar 25 19:25:12 volumiokueche systemd[1]: sshtunnel.service: Deactivated successfully.
Mar 25 19:25:12 volumiokueche systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel.
Mar 25 19:25:12 volumiokueche systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Mar 25 19:25:12 volumiokueche sudo[15939]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:12 volumiokueche volumio[15378]: info: Remote SSH Started
Mar 25 19:25:12 volumiokueche autossh[15942]: port set to 0, monitoring disabled
Mar 25 19:25:12 volumiokueche autossh[15942]: starting ssh (count 1)
Mar 25 19:25:12 volumiokueche autossh[15942]: ssh child pid is 15945
Mar 25 19:25:12 volumiokueche volumio[15378]: info: Setting Geolocation for MyVolumio to eu10
Mar 25 19:25:12 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:12 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:12 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:13 volumiokueche volumio[15378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Mar 25 19:25:13 volumiokueche volumiossh-tunnel[15945]: Warning: Permanently added '[eu1.myvolumio.org]:2222' (RSA) to the list of known hosts.
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::volumioGetState
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CorePlayQueue::getTrack 0
Mar 25 19:25:13 volumiokueche volumio[15378]: info: Setting Geolocation for MyVolumio to eu11
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:13 volumiokueche volumio[15378]: info: Successfully Added MyVolumio device
Mar 25 19:25:13 volumiokueche volumio[15378]: info: Successfully Added MyVolumio device
Mar 25 19:25:13 volumiokueche sudo[15947]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service
Mar 25 19:25:13 volumiokueche sudo[15947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:25:13 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 76.
Mar 25 19:25:13 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:13 volumiokueche volumio[15378]: info: Updating MyVolumio device info
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:13 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:14 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:14 volumiokueche go-librespot[15949]: go-librespot daemon starting...
Mar 25 19:25:14 volumiokueche systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service...
Mar 25 19:25:14 volumiokueche systemd[1]: mpd_monitor.service: Deactivated successfully.
Mar 25 19:25:14 volumiokueche systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service.
Mar 25 19:25:14 volumiokueche systemd[1]: Started mpd_monitor.service - MPD Monitor Service.
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=debug msg="app state loaded"
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:14 volumiokueche sudo[15947]: pam_unix(sudo:session): session closed for user root
Mar 25 19:25:14 volumiokueche mpd_monitor.sh[15956]: MPD Monitor Service: Starting MPD Monitor Service
Mar 25 19:25:14 volumiokueche volumio[15378]: info: Successfully started MPD Monitor
Mar 25 19:25:14 volumiokueche volumio[15378]: info: Updating MyVolumio device info
Mar 25 19:25:14 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:14 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:14 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+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 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+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 25 19:25:14 volumiokueche volumio[15378]: info: MRS: Getting audio outputs on start
Mar 25 19:25:14 volumiokueche volumio[15378]: info: MRS: Requesting all other devices output
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=info msg="zeroconf server listening on port 40827"
Mar 25 19:25:14 volumiokueche volumio[15378]: info: Successfully Updated MyVolumio device
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=debug msg="obtained new client token: AAAW2l590RVb7L+6OELMavdjWk/oZrwOi59X9+zvRvcHaHQeymo6esby3C0CFa9S6MfBuS9943dI1Te5YWro4KrAQJWTOfpbzT1ljVBebAeULxfcJskNyetcNK+nKWqvmO2lIO6N7k+xlseC4uxDFjvCD1fgMj/EW6ADdM4gs8SrLsTqJZEa+CmlhUuLYpPyOhMsz1bL/StBFHTN7+lSYFQBQL/NeMgkeQd5xwGj4S3pdz0VWiYKYvg="
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25:14+01:00" level=debug msg="completed challenge"
Mar 25 19:25:14 volumiokueche go-librespot[15950]: time="2026-03-25T19:25: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 25 19:25:14 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:14 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:14 volumiokueche volumio[15378]: info: Successfully Updated MyVolumio device
Mar 25 19:25:15 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:15 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:15 volumiokueche systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 518.
Mar 25 19:25:15 volumiokueche systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:25:15 volumiokueche systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:25:15 volumiokueche upmpdcli[15976]: Could not open config: /tmp/upmpdcli.conf
Mar 25 19:25:15 volumiokueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:15 volumiokueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Mar 25 19:25:16 volumiokueche volumio[15378]: info: BOOT COMPLETED
Mar 25 19:25:16 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 25 19:25:16 volumiokueche volumio[15378]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Mar 25 19:25:16 volumiokueche volumio[15378]: info: CoreCommandRouter::volumioGetState
Mar 25 19:25:16 volumiokueche volumio[15378]: info: CorePlayQueue::getTrack 0
Mar 25 19:25:17 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 77.
Mar 25 19:25:17 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:17 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:17 volumiokueche go-librespot[15977]: go-librespot daemon starting...
Mar 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+01:00" level=debug msg="app state loaded"
Mar 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+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 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+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 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+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 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+01:00" level=info msg="zeroconf server listening on port 40261"
Mar 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+01:00" level=debug msg="obtained new client token: AAAcUBDyCm/37WLioSFQb8A0TlxgRx2OU3ioFQZaZMX9FCb6h8AISlXrJq09R0anE26H78yHWVIXUhUy0T6Z3lHpX6EFjnZUZnB94B4rlHShcU/jGu4itWqx0caAL1sHvqySnLb3tLQFIq6o9TFphGhxtPQSKxkZoK5+uyddHnR3W1rRLe4Piy3/Kfm4zYtG/PkjdJIFzfsK3ncE1j2sQ7GPVxNNX/JZe4TKmd3dxbmihafhPfKXoIA="
Mar 25 19:25:17 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:18 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:18+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:18 volumiokueche go-librespot[15978]: time="2026-03-25T19:25:18+01:00" level=debug msg="completed challenge"
Mar 25 19:25:18 volumiokueche go-librespot[15978]: time="2026-03-25T19:25: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 25 19:25:18 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:18 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:18 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:18 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:19 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 25 19:25:19 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 25 19:25:19 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 25 19:25:20 volumiokueche volumio-remote-updater[752]: Test mode disabled
Mar 25 19:25:20 volumiokueche volumio-remote-updater[752]: Alpha mode disabled
Mar 25 19:25:20 volumiokueche volumio-remote-updater[752]: Alpha legacy test mode disabled
Mar 25 19:25:20 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 25 19:25:20 volumiokueche volumio[15378]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for BLE notification issues
\nFix for mDNS/BCT
\nFix wireless init on Pi
\nFix raspi-config abuse on Pi
\n
\nNEW ADDITIONS
\n\nBluetooth party mode
\nUpdate Tidal SDK
\nUpdate Qobuz Connect SDK
\n\n","title":"Update v4.103","updateavailable":true}
Mar 25 19:25:20 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 25 19:25:21 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:21 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:21 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 78.
Mar 25 19:25:21 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:21 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:21 volumiokueche go-librespot[15989]: go-librespot daemon starting...
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=debug msg="app state loaded"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25: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 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25: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 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25: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 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=info msg="zeroconf server listening on port 39515"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=debug msg="obtained new client token: AABbQtOkQqQsNhHhDoQQbCgDTV8EupghCjqyjoAEJ+NPWEQmbJP2WPFWq8AbElMen63hMRL7upbBx4maYUZhIiyb7JsJrAsBH2xxLhDKoP1tN18k7ikBt/PRz0co8fhabrV5B5VQvdncd3fQ99X0MzFao1OOiBzhJrwVzZBxSHTndcCTp7xDWWKGsLvnh8Tp4dDSn7u/1y3GGXfJpM4cGKqs7/WWUIq7A0M99b3V1ATEIJmRX5uK63c="
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+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 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:80"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=debug msg="completed challenge"
Mar 25 19:25:21 volumiokueche go-librespot[15990]: time="2026-03-25T19:25:21+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:25:21 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:21 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 25 19:25:21 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 25 19:25:24 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:24 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:24 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 79.
Mar 25 19:25:24 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:24 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:24 volumiokueche go-librespot[16012]: go-librespot daemon starting...
Mar 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+01:00" level=debug msg="app state loaded"
Mar 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+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 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+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 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+01:00" level=info msg="zeroconf server listening on port 43485"
Mar 25 19:25:24 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:24+01:00" level=debug msg="obtained new client token: AAAWIAAE2qR8FdOKzjIY2Um5Gd4cZ73bridjoGAXbE4Bj2K6qlVDEkJLCOUqJEzWs+7i+cRrGx6wSy0DzGn9ausHHUr4Vpgw1gCtM3ZJU6l4kVal2ep6iy8ZJx1G+pXDn90YxyHvpl8K4YhoORaZ1FqiF9J5ZLJH5y99VW1UJ8uBVbQ6ZDw7tLf+j8mQKr3xqsfyEfO4gHBo0nBVK97lAF7e/Jajp8ucXDVQyrdbsxprRYwnt+YrtM8="
Mar 25 19:25:25 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:25 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:25+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:25 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:25+01:00" level=debug msg="completed challenge"
Mar 25 19:25:25 volumiokueche go-librespot[16013]: time="2026-03-25T19:25:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:25:25 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:25 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:27 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:27 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:28 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 80.
Mar 25 19:25:28 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:28 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:28 volumiokueche go-librespot[16020]: go-librespot daemon starting...
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=debug msg="app state loaded"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+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 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+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 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=info msg="zeroconf server listening on port 40463"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=debug msg="obtained new client token: AACKcWrFPaa59AbrT4JdhFHi6QQdiBQ7JQfpstyu/Po2x+wcBhh8LC4U0l2vsKUNnb7Ptl9/9vzmbDCaXC9iaa0Vd9U5UbQnW7G6SlcK119SwNG47Bpq81kTo9qu3WF7Crp32XSesB0/80aY9xLP+ncMdYsMrtvrcQ2Eh5CF0F5QEBcbmeuI0M0bGJYTzGMPXZKdr1xFH366/c/5Ymp/i6zedks8cspoVb0wBaKgb7A9KZx0juXB5uM="
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=debug msg="completed challenge"
Mar 25 19:25:28 volumiokueche go-librespot[16021]: time="2026-03-25T19:25:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:25:28 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:28 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:30 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:30 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:30 volumiokueche systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 519.
Mar 25 19:25:30 volumiokueche systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:25:30 volumiokueche systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:25:30 volumiokueche upmpdcli[16029]: Could not open config: /tmp/upmpdcli.conf
Mar 25 19:25:30 volumiokueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:30 volumiokueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Mar 25 19:25:31 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 81.
Mar 25 19:25:31 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:31 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:31 volumiokueche go-librespot[16030]: go-librespot daemon starting...
Mar 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+01:00" level=debug msg="app state loaded"
Mar 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+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 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+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 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+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 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+01:00" level=info msg="zeroconf server listening on port 43701"
Mar 25 19:25:31 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:31+01:00" level=debug msg="obtained new client token: AACTvKLltjrGA2pOTdnSQYo/SaWAFp5DHUhKEYFviWBAuEL5gwnG+fUKVaTF4mEseDJiUFOQPguDjYoHkEafh6UID76sWlzKBSxxVzI5QPG1Zv2dHqk4x1rmxRba7+028rRFAyr+n2UNoS1f1SyhE/s+Ex3bIxAUywXNLnoHGOyvf6YrNE6YAYKzhmLBl0f658/2Xc6qxCak18sECgIi9p+OExeYY634Vynj8KXkDQCyWA2o9lWtaZk="
Mar 25 19:25:32 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:32+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:32 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:32+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:32 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:32+01:00" level=debug msg="completed challenge"
Mar 25 19:25:32 volumiokueche go-librespot[16031]: time="2026-03-25T19:25:32+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:25:32 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:32 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:33 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:33 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:35 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 82.
Mar 25 19:25:35 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:35 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:35 volumiokueche go-librespot[16055]: go-librespot daemon starting...
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=debug msg="app state loaded"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+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 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+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 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+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 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=info msg="zeroconf server listening on port 35659"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=debug msg="obtained new client token: AADQ3aNlrs9YMJmKtzt7kLslEhxrQjKcAbtkK+HurRRASbgqKwCF1sRJtS99op7OBDEER2+1QOz9JFsaZsh/ZwFeyBlludujQQk7FJxN7ajc8VeWY4XV46d59CPnpKPRLYUNv3IyihKQ118+Kyx0BKrp9YWGtsHdqdD6xHDDaQEIyLDLiXJOITUYBDQGuMCNAsoMwT2El4a4LY9QVV+9bk2yiTZEydpWB1OT7rIFQDxrtFg6hAHoYWc="
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+01:00" level=debug msg="completed challenge"
Mar 25 19:25:35 volumiokueche go-librespot[16056]: time="2026-03-25T19:25:35+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 25 19:25:35 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:35 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:36 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:36 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:38 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 83.
Mar 25 19:25:38 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:38 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:38 volumiokueche go-librespot[16063]: go-librespot daemon starting...
Mar 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+01:00" level=debug msg="app state loaded"
Mar 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]"
Mar 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+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 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+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 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+01:00" level=info msg="zeroconf server listening on port 37563"
Mar 25 19:25:38 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:38+01:00" level=debug msg="obtained new client token: AACbvCeu4obto+zsPsHGXAIcyYr1DCNk3x4qA1Qg2m3LZOW0uWUc1XafeLl95QmFc9jtTWB6ftfIr7D0uAT5md878lTBo7aGzdXLCd1j1Z4raS7vY+whwe76CWfi0JT7b4oG/ShFI3a3WB3Tp3CHLajYLnZfyqWdxX9ypdKJ5SAK9un/dPXv/jisqs+HurZDF84v9Q8Q6c8yD5uSFF+qdAplQpAQ1BGAuf6Gb75Yv9TljBTLyyxqdOY="
Mar 25 19:25:39 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:39 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:39+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:39 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:39+01:00" level=debug msg="completed challenge"
Mar 25 19:25:39 volumiokueche go-librespot[16064]: time="2026-03-25T19:25:39+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 25 19:25:39 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:39 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:39 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:39 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:42 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:42 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:42 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 84.
Mar 25 19:25:42 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:42 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:42 volumiokueche go-librespot[16072]: go-librespot daemon starting...
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=debug msg="app state loaded"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+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 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+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 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=info msg="zeroconf server listening on port 36645"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=debug msg="obtained new client token: AAD/kgeyaPLx1IeBiZMJdTIR9Vjq1uzdsfG4B6TweV92VEFw6CrTB5q+EmA9phGGr/JN5VPknbobB7/eR6FYKg1DpMLF76fP+2CZfb+O7X1BOgJb25AM+dw23OgWU9CQAFZkiqrmYhONu4jSWHspZdnE3bdiDNh9i2gOwh80ofrkI50bpkYhwdEm71H6kdJg9dK+XBw1J2ZaTF3EmZ+1TUtyhS4q4Xo2S2GZTJU3NpHRBCfIvyBXwm4="
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25:42+01:00" level=debug msg="completed challenge"
Mar 25 19:25:42 volumiokueche go-librespot[16073]: time="2026-03-25T19:25: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 25 19:25:42 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:42 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:45 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:45 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:45 volumiokueche systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 520.
Mar 25 19:25:45 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 85.
Mar 25 19:25:45 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:45 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:45 volumiokueche systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:25:45 volumiokueche go-librespot[16095]: go-librespot daemon starting...
Mar 25 19:25:45 volumiokueche systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+01:00" level=debug msg="app state loaded"
Mar 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:45 volumiokueche upmpdcli[16097]: Could not open config: /tmp/upmpdcli.conf
Mar 25 19:25:45 volumiokueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:45 volumiokueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Mar 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+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 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+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 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+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 25 19:25:45 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:45+01:00" level=info msg="zeroconf server listening on port 44775"
Mar 25 19:25:46 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:46+01:00" level=debug msg="obtained new client token: AABbxp+jqSZ6NuzJAY8sz88B0jakthLqU9gco59faJ1VBFYf89ZVDVBWeNK+PIgD4UmbP+I0K7Xv7GKEMBLgzOyOvaUSQbUxW5x4IpQbvj7qvIB9JLP9gcy+NvlF2XZMfuAXNFLnbpVPCYhacZ4D7jJtQUIU+q81vVBRVw2XNbGIEuxNngDgpLZAE0QsqmeFsmhz8yqCol+4CjQDal7Ejq3OeyJyLQvN/hDPsIx/El+GAzm5OkYLDIo="
Mar 25 19:25:46 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:46+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 25 19:25:46 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:443"
Mar 25 19:25:46 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:46+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:46 volumiokueche go-librespot[16096]: time="2026-03-25T19:25:46+01:00" level=debug msg="completed challenge"
Mar 25 19:25:46 volumiokueche go-librespot[16096]: time="2026-03-25T19:25: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 25 19:25:46 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:46 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:48 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:48 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:49 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 86.
Mar 25 19:25:49 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:49 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:49 volumiokueche go-librespot[16104]: go-librespot daemon starting...
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=debug msg="app state loaded"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+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 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+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 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+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 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=info msg="zeroconf server listening on port 45999"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=debug msg="obtained new client token: AADg5WTtd49vdGpckMdzomlevX9du/PrjS8P/ypA4E5wqSKFsmkO69GKC2fOX/y0TGOq8qQAKmwncygBaM0X8NGZ+GtxkxK3bC0q+R4brHX+ZXZyoFnB7THPDRi0GWv1UcWS07kFGfRXZFS3QgH1/TaIYV5eg98TG+6zeKjdz9XFC9YXH8qi2xzxV1oHDbtt+AZqXipdkWIjbsVNsvTDETNwzbWx7jLuzrJf03sK/BMQAskkWYs/hfo="
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+01:00" level=debug msg="completed challenge"
Mar 25 19:25:49 volumiokueche go-librespot[16105]: time="2026-03-25T19:25:49+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 25 19:25:49 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:49 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:50 volumiokueche volumio[15378]: info: UPDATER: Scheduling automatic update
Mar 25 19:25:50 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Mar 25 19:25:50 volumiokueche volumio[15378]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Mar 25 19:25:50 volumiokueche volumio[15378]: info: UPDATER: Auto update will take place at: Thu Mar 26 2026 04:43:14 GMT+0100 (Central European Standard Time)
Mar 25 19:25:51 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:51 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:52 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 87.
Mar 25 19:25:52 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:52 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:52 volumiokueche go-librespot[16112]: go-librespot daemon starting...
Mar 25 19:25:52 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:52+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:52 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:52+01:00" level=debug msg="app state loaded"
Mar 25 19:25:52 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:52+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+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 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+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 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+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 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+01:00" level=info msg="zeroconf server listening on port 34975"
Mar 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+01:00" level=debug msg="obtained new client token: AABnO76nfQglKZdyJA4QEgEVJn4BwMNhNt+cT9vrQW8k68zVNeJTDcDqYbF0Ir9AqrcGI7tzAazT5Fyil8s5pgxSjpISh5xojogGAC+xHQutif4sCAJPx1twlUGIdNX3EJtoacz7XBWccVPCHI1Qc2cU+fyVgMUzAu2sCp6qes6yOha9t1xiCtE1rhuO1EwfLm7fFLDNlL9Ro/kapfmTjKv6aKalG+yfondUpnR28E8X1XFBBrp9"
Mar 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+01:00" level=debug msg="completed challenge"
Mar 25 19:25:53 volumiokueche go-librespot[16113]: time="2026-03-25T19:25:53+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 25 19:25:53 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:53 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:54 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:54 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:56 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 88.
Mar 25 19:25:56 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:56 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:56 volumiokueche go-librespot[16137]: go-librespot daemon starting...
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=debug msg="app state loaded"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+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 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+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 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=info msg="zeroconf server listening on port 37323"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=debug msg="obtained new client token: AACCIKcH/xTfASxfZ5TgYFtpiqImxavRwgblp1VlDTL63050bt/4jZDwO26kAECnom/NAAKVql25vmBBMNC0zWJIn2wqcQW1C2iYIsIWPz3ltfFWFVF/x1opWxnUY9F5FZWxYE9LPPt9VGtrM348lHx5KDdOAMTYfDcHWwk+6644kpFLtplN4Hqx+mvsIbxHOIQMBKRtL/k7Ue1UzBYJ6TGmGMFgIsjVEIHlndAWCWZA/P6wP20Cui8="
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=debug msg="completed keyexchange"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=debug msg="completed challenge"
Mar 25 19:25:56 volumiokueche go-librespot[16138]: time="2026-03-25T19:25:56+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:25:56 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:25:56 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:25:57 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:25:57 volumiokueche volumio[15378]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:25:59 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 89.
Mar 25 19:25:59 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:59 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:25:59 volumiokueche go-librespot[16145]: go-librespot daemon starting...
Mar 25 19:25:59 volumiokueche go-librespot[16146]: time="2026-03-25T19:25:59+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:25:59 volumiokueche go-librespot[16146]: time="2026-03-25T19:25:59+01:00" level=debug msg="app state loaded"
Mar 25 19:25:59 volumiokueche go-librespot[16146]: time="2026-03-25T19:25:59+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+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 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+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 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=info msg="zeroconf server listening on port 36127"
Mar 25 19:26:00 volumiokueche volumio[15378]: info: Initializing connection to go-librespot Websocket
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=debug msg="new websocket client"
Mar 25 19:26:00 volumiokueche volumio[15378]: info: Connection to go-librespot Websocket established
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=debug msg="obtained new client token: AAD4fEqxFfJVXJUD47wZzgD927lrA87EpZQ7S0QRPP+j2i79GLZM8fwXVKF/rMblBCfY0YIcEKYSI46Y+U8+8KzUX4FQPDHc7PvCC/1gUM1yE42si08xsA84Tkt3rXXrNCmM8IaVkGMTp74ErQiNvwv943DEZ5zpUDEaQQxxfU/CuS0uYV2VfFHIUQiCUjQ4XuYwHUd6yCS9vbPhGhFnklZnwAgZL8HQrEnQIDhTtuM+WJHeQP58"
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=debug msg="completed keyexchange"
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=debug msg="completed challenge"
Mar 25 19:26:00 volumiokueche go-librespot[16146]: time="2026-03-25T19:26:00+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials "
Mar 25 19:26:00 volumiokueche systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:26:00 volumiokueche systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 25 19:26:00 volumiokueche volumio[15378]: info: Connection to go-librespot Websocket closed
Mar 25 19:26:00 volumiokueche systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 521.
Mar 25 19:26:00 volumiokueche systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:26:00 volumiokueche systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Mar 25 19:26:00 volumiokueche upmpdcli[16154]: Could not open config: /tmp/upmpdcli.conf
Mar 25 19:26:00 volumiokueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Mar 25 19:26:00 volumiokueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Mar 25 19:26:03 volumiokueche volumio[15378]: info: Getting Spotify volume
Mar 25 19:26:03 volumiokueche volumio[15378]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 25 19:26:03 volumiokueche volumio[15378]: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 25 19:26:03 volumiokueche volumio[15378]:     at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) {
Mar 25 19:26:03 volumiokueche volumio[15378]:   errno: -111,
Mar 25 19:26:03 volumiokueche volumio[15378]:   code: 'ECONNREFUSED',
Mar 25 19:26:03 volumiokueche volumio[15378]:   syscall: 'connect',
Mar 25 19:26:03 volumiokueche volumio[15378]:   address: '127.0.0.1',
Mar 25 19:26:03 volumiokueche volumio[15378]:   port: 9879,
Mar 25 19:26:03 volumiokueche volumio[15378]:   response: undefined
Mar 25 19:26:03 volumiokueche volumio[15378]: }
Mar 25 19:26:03 volumiokueche volumio[15378]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 25 19:26:03 volumiokueche systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 90.
Mar 25 19:26:03 volumiokueche systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:26:03 volumiokueche systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Mar 25 19:26:03 volumiokueche go-librespot[16167]: go-librespot daemon starting...
Mar 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26:03+01:00" level=info msg="running go-librespot 0.4.0"
Mar 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26:03+01:00" level=debug msg="app state loaded"
Mar 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26:03+01:00" level=info msg="api server listening on 127.0.0.1:9879"
Mar 25 19:26:03 volumiokueche sudo[16176]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 19:25'
Mar 25 19:26:03 volumiokueche sudo[16176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Mar 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26:03+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 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26:03+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 25 19:26:03 volumiokueche go-librespot[16168]: time="2026-03-25T19:26:03+01:00" level=info msg="zeroconf server listening on port 42683"
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"