Mar 04 15:28:00 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:00 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:00 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:02 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:02 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:02 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:04 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:04 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:04 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:05 loe go-librespot[30540]: time="2026-03-04T15:28:05+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Mar 04 15:28:05 loe volumio[30190]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Mar 04 15:28:05 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:05 loe volumio[30190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 15:28:05 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:05 loe volumio[30190]: Error: socket hang up Mar 04 15:28:05 loe volumio[30190]: at connResetException (node:internal/errors:720:14) Mar 04 15:28:05 loe volumio[30190]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 04 15:28:05 loe volumio[30190]: at Socket.emit (node:events:526:35) Mar 04 15:28:05 loe volumio[30190]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 04 15:28:05 loe volumio[30190]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 04 15:28:05 loe volumio[30190]: code: 'ECONNRESET', Mar 04 15:28:05 loe volumio[30190]: response: undefined Mar 04 15:28:05 loe volumio[30190]: } Mar 04 15:28:05 loe volumio[30190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 15:28:06 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:06 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:06 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:06 loe sudo[31667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 15:27' Mar 04 15:28:06 loe sudo[31667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:06 loe sudo[31667]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:07 loe qobuz-connect[30715]: 20260304 15:28:07.243 [30715.30715] INFO SampleApp: Socket closed Mar 04 15:28:07 loe volumio-remote-updater[714]: [2026-03-04 15:28:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 04 15:28:07 loe vtcs[30761]: [2026-03-04 15:28:07.245] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 04 15:28:07 loe systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:07 loe volumio-remote-updater[714]: [2026-03-04 15:28:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 04 15:28:07 loe volumio5-onboarding[2840]: time=2026-03-04T15:28:07.248+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 04 15:28:07 loe volumio5-onboarding[2840]: time=2026-03-04T15:28:07.271+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 04 15:28:07 loe systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 04 15:28:07 loe systemd[1]: volumio.service: Consumed 1min 11.107s CPU time. Mar 04 15:28:07 loe systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 04 15:28:07 loe systemd[1]: volumio.service: Scheduled restart job, restart counter is at 825. Mar 04 15:28:07 loe systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 04 15:28:07 loe systemd[1]: volumio.service: Consumed 1min 11.107s CPU time. Mar 04 15:28:07 loe systemd[1]: Started volumio.service - Volumio Backend Module. Mar 04 15:28:07 loe systemd[1]: dynamicswap.service: Deactivated successfully. Mar 04 15:28:08 loe volumio5-onboarding[2840]: time=2026-03-04T15:28:08.273+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 04 15:28:08 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 04 15:28:08 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:08 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:08 loe go-librespot[31715]: go-librespot daemon starting... Mar 04 15:28:08 loe go-librespot[31716]: time="2026-03-04T15:28:08+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:08 loe go-librespot[31716]: time="2026-03-04T15:28:08+01:00" level=debug msg="app state loaded" Mar 04 15:28:08 loe go-librespot[31716]: time="2026-03-04T15:28:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:08 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:08 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:08 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+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 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+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 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+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 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+01:00" level=info msg="zeroconf server listening on port 39417" Mar 04 15:28:09 loe volumio5-onboarding[2840]: time=2026-03-04T15:28:09.275+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+01:00" level=debug msg="obtained new client token: AABJmPxbacyZ4SFBzTC4pqfUu1gGaDilsQJ24TyPrX8JBbBr8kg3/nZ2dCsGK5YK5Bc1bz6ukF1C9mvSbtRjZ33OHMU7uJO8Rj96Zdu9qpjCoJYbMLDXmMxsxQwcxGGZzqEgsqnoaTs9aDHlfAzy05hnaUehZJu1JOvgCY37nu8ySTClbZzGocprDAc+Z0BE3cG28IfUoBL/a0LrOubSJCUOOqtZy1PYP9TtnvYG3Ycv9maiE2hRXMmBkA==" Mar 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+01:00" level=debug msg="completed challenge" Mar 04 15:28:09 loe go-librespot[31716]: time="2026-03-04T15:28:09+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:28:09 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:09 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:10 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:10 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:10 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:10 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:10 loe volumio[31703]: info: ----- Volumio3 ---- Mar 04 15:28:10 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:10 loe volumio[31703]: info: ----- System startup ---- Mar 04 15:28:10 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:12 loe volumio-remote-updater[714]: [2026-03-04 15:28:12] [connect] Successful connection Mar 04 15:28:12 loe volumio[31703]: info: MYVOLUMIO Environment detected Mar 04 15:28:12 loe volumio[31703]: info: Plugin folders cleanup Mar 04 15:28:12 loe volumio[31703]: info: Scanning into folder /volumio/app/plugins/ Mar 04 15:28:12 loe volumio[31703]: info: Scanning category audio_interface Mar 04 15:28:12 loe volumio[31703]: info: Scanning category miscellanea Mar 04 15:28:12 loe volumio[31703]: info: Scanning category music_service Mar 04 15:28:12 loe volumio[31703]: info: Scanning category plugins.json Mar 04 15:28:12 loe volumio[31703]: info: Scanning category system_controller Mar 04 15:28:12 loe volumio[31703]: info: Scanning category user_interface Mar 04 15:28:12 loe volumio[31703]: info: Scanning into folder /data/plugins/ Mar 04 15:28:12 loe volumio[31703]: info: Scanning category music_service Mar 04 15:28:12 loe volumio[31703]: info: Plugin folders cleanup completed Mar 04 15:28:12 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:12 loe volumio[31703]: info: ----- Core plugins startup ---- Mar 04 15:28:12 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:12 loe volumio[31703]: info: Loading plugins from folder /volumio/app/plugins/ Mar 04 15:28:12 loe volumio[31703]: info: Adding plugin upnp to MyMusic Plugins Mar 04 15:28:12 loe volumio[31703]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 04 15:28:12 loe volumio[31703]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 04 15:28:12 loe volumio[31703]: info: Loading plugins from folder /data/plugins/ Mar 04 15:28:12 loe volumio[31703]: info: Loading plugin "system"... Mar 04 15:28:12 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Mar 04 15:28:12 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:12 loe volumio[31703]: info: Loading plugin "appearance"... Mar 04 15:28:12 loe go-librespot[31734]: go-librespot daemon starting... Mar 04 15:28:12 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:12 loe go-librespot[31738]: time="2026-03-04T15:28:12+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:12 loe go-librespot[31738]: time="2026-03-04T15:28:12+01:00" level=debug msg="app state loaded" Mar 04 15:28:12 loe go-librespot[31738]: time="2026-03-04T15:28:12+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:12 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:12 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:12 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+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 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+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 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+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 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+01:00" level=info msg="zeroconf server listening on port 38877" Mar 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+01:00" level=debug msg="obtained new client token: AAAUGVuxyupXlVxUIt4CloQCQnbggUhZtpNb6Pygfxvclmi7nw02t1+Y3pROILHIQrQD+f7aMTaIG+smwc8eAhD5+/eJ8eMaDhIn2xtiCEkyLoEEXbklyxw2BAeoEmg11IaunbO8khSmqbVqVK4+dW6fBCO4rTqe011BVuzp7aG+jfMcEn99dt6qR2WwMSXMgZ5z3oAJtu5mkHnVO+OF5PmaS4VmpCpicR7vYNSugzce8PQ3Xt4qqzA=" Mar 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+01:00" level=debug msg="completed challenge" Mar 04 15:28:13 loe go-librespot[31738]: time="2026-03-04T15:28:13+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:28:13 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:13 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "network"... Mar 04 15:28:14 loe volumio[31703]: info: Refreshing Cached IP Addresses Mar 04 15:28:14 loe sudo[31746]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 15:28:14 loe sudo[31746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:14 loe sudo[31746]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "services"... Mar 04 15:28:14 loe sudo[31748]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 15:28:14 loe sudo[31748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "volumio5onboarding"... Mar 04 15:28:14 loe sudo[31748]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "alsa_controller"... Mar 04 15:28:14 loe sudo[31760]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 04 15:28:14 loe sudo[31760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:14 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:14 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:14 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:14 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "wizard"... Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "networkfs"... Mar 04 15:28:14 loe volumio[31703]: info: Starting Udev Watcher for removable devices Mar 04 15:28:14 loe volumio[31703]: info: Ignoring mount for partition: boot Mar 04 15:28:14 loe volumio[31703]: info: Ignoring mount for partition: volumio Mar 04 15:28:14 loe volumio[31703]: info: Ignoring mount for partition: volumio_data Mar 04 15:28:14 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "volumio_command_line_client"... Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "upnp"... Mar 04 15:28:14 loe volumio[31703]: info: [1772634494929] Starting Upmpd Daemon Mar 04 15:28:14 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "my_music"... Mar 04 15:28:14 loe volumio[31703]: info: Loading plugin "mpd"... Mar 04 15:28:15 loe sudo[31760]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:15 loe volumio[31703]: info: Loading plugin "upnp_browser"... Mar 04 15:28:16 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Mar 04 15:28:16 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:16 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:16 loe go-librespot[31780]: go-librespot daemon starting... Mar 04 15:28:16 loe go-librespot[31781]: time="2026-03-04T15:28:16+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:16 loe go-librespot[31781]: time="2026-03-04T15:28:16+01:00" level=debug msg="app state loaded" Mar 04 15:28:16 loe go-librespot[31781]: time="2026-03-04T15:28:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:16 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:16 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:16 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+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 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+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 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+01:00" level=info msg="zeroconf server listening on port 34671" Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+01:00" level=debug msg="obtained new client token: AABS8k9NjZbBbVhjOOoidDmD3Aecn0VPEGDrWwLw1X4hO63Ueo0LPqeWHTRXYbCsz+q649q454IUyUl7cGAp599RC2VtPDKbJTOpg4x9RVhMnb6NsAKHQRyHIkNAenqKwt9X+Eb7pwJe7rGNTb3tpQJiX8mrc2b76InBNUPKSWz7L87l2Zg7m0fvy2t91zqjmCRAKGfk+PG8NMrKXwWMDQXhIbVYkq7N+VNyKxjk8VM2sSld1ARre0N75g==" Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+01:00" level=debug msg="completed challenge" Mar 04 15:28:17 loe go-librespot[31781]: time="2026-03-04T15:28:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:28:17 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:17 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:18 loe volumio[31703]: info: Starting UPNP Browser Mar 04 15:28:18 loe volumio[31703]: info: Loading plugin "alarm-clock"... Mar 04 15:28:18 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:18 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:18 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:18 loe volumio[31703]: info: Loading plugin "airplay_emulation"... Mar 04 15:28:18 loe volumio[31703]: info: Starting Shairport Sync Mar 04 15:28:18 loe volumio[31703]: info: Loading plugin "last_100"... Mar 04 15:28:18 loe volumio[31703]: info: Loading plugin "webradio"... Mar 04 15:28:19 loe volumio[31703]: info: Loading plugin "i2s_dacs"... Mar 04 15:28:19 loe volumio[31703]: info: Loading plugin "volumiodiscovery"... Mar 04 15:28:19 loe volumio[31703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 04 15:28:19 loe volumio[31703]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 15:28:19 loe volumio[31703]: *** WARNING *** For more information see Mar 04 15:28:19 loe volumio[31703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 04 15:28:19 loe volumio[31703]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 15:28:19 loe volumio[31703]: *** WARNING *** For more information see Mar 04 15:28:19 loe node[31703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 04 15:28:19 loe node[31703]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 15:28:19 loe node[31703]: *** WARNING *** For more information see Mar 04 15:28:19 loe node[31703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 04 15:28:19 loe node[31703]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 04 15:28:19 loe node[31703]: *** WARNING *** For more information see Mar 04 15:28:19 loe volumio[31703]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 04 15:28:19 loe volumio[31703]: info: Discovery: Started advertising with name: Loe Mar 04 15:28:19 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 04 15:28:19 loe volumio[31703]: info: Loading plugin "spop"... Mar 04 15:28:20 loe volumio5-onboarding[2840]: time=2026-03-04T15:28:20.277+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="read tcp 127.0.0.1:59572->127.0.0.1:3000: i/o timeout" Mar 04 15:28:20 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Mar 04 15:28:20 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:20 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:20 loe go-librespot[31790]: go-librespot daemon starting... Mar 04 15:28:20 loe go-librespot[31791]: time="2026-03-04T15:28:20+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:20 loe go-librespot[31791]: time="2026-03-04T15:28:20+01:00" level=debug msg="app state loaded" Mar 04 15:28:20 loe go-librespot[31791]: time="2026-03-04T15:28:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:20 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:20 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:20 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28: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 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28: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 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28: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 04 15:28:21 loe volumio[31703]: info: Loading plugin "outputs"... Mar 04 15:28:21 loe volumio[31703]: info: Loading plugin "albumart"... Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28:21+01:00" level=info msg="zeroconf server listening on port 46649" Mar 04 15:28:21 loe volumio[31703]: info: Plugin example_plugin is not enabled Mar 04 15:28:21 loe volumio[31703]: info: Loading plugin "inputs"... Mar 04 15:28:21 loe volumio[31703]: info: Loading plugin "updater_comm"... Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28:21+01:00" level=debug msg="obtained new client token: AABI4ppDrKY+k/bEY8HFzD2fGUK2Ar9llOxjRc2KQsYOrz88CKtppqLiSle/v8cUdsJ6eYzpXZyWoMknBqKmweRxeZIEU4i8ZwW3wEO+GWumnqT2jq6d7kuLTq/RpZz0rqPnitjS0CmV+bEd6KvER8XdeK31naK2OM7RKlwDirWMz82Kp2xX6KVtt1rIiSeN4aeKBGVbTwtDfb4GdN7e/+vTsp9kyCr4kJiFFpCdocC8SxXIN9grMjcERA==" Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28:21+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28:21+01:00" level=debug msg="completed challenge" Mar 04 15:28:21 loe go-librespot[31791]: time="2026-03-04T15:28: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 04 15:28:21 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:21 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:21 loe volumio[31703]: info: Plugin mpdemulation is not enabled Mar 04 15:28:21 loe volumio[31703]: info: Loading plugin "rest_api"... Mar 04 15:28:21 loe volumio[31703]: info: Loading plugin "websocket"... Mar 04 15:28:21 loe volumio[31703]: info: Starting Socket.io Server version 1.7.4 Mar 04 15:28:21 loe volumio[31703]: info: Loading i18n strings for locale nl Mar 04 15:28:21 loe volumio[31703]: Updating browse sources language Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::initPlayerControls Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:21 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 15:28:22 loe volumio[31703]: Express server listening on port 3000 Mar 04 15:28:22 loe volumio[31703]: [Metrics] WebUI: 11s 983.56ms Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::resetVolumioState Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::getcurrentVolume Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 15:28:22 loe volumio[31703]: info: Volumio Network Manager: Network status updated: 1 Mar 04 15:28:22 loe volumio[31798]: Forking 3 albumart workers Mar 04 15:28:22 loe volumio[31703]: info: VolumeController:: Volume=5 Mute =false Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:28:22 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::updateTrackBlock Mar 04 15:28:22 loe volumio[31703]: info: CorePlayQueue::getTrackBlock Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::volumioRetrievevolume Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:22 loe volumio[31703]: info: Reloading queue from file Mar 04 15:28:22 loe volumio[31703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::setRepeat false single undefined Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:28:22 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::setRandom undefined Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:28:22 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:28:22 loe volumio[31703]: info: Setting Device type: Raspberry PI Mar 04 15:28:22 loe volumio[31703]: info: Completed loading Core Plugins Mar 04 15:28:22 loe volumio[31703]: info: Preparing to generate the ALSA configuration file Mar 04 15:28:22 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:22 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:22 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:22 loe volumio[31703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 15:28:22 loe volumio[31703]: info: VolumeController:: Volume=5 Mute =false Mar 04 15:28:22 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:28:22 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:22 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:28:22 loe volumio[31703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 15:28:22 loe volumio[31703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Mar 04 15:28:23 loe volumio[31703]: info: Discovery: adding 07fbb113-343c-4b34-af49-444dc3d8fb26 Mar 04 15:28:23 loe volumio[31703]: info: Discovery: Found device Loe Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:28:23 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:23 loe volumio[31703]: info: Discovery: this is already registered, 07fbb113-343c-4b34-af49-444dc3d8fb26 Mar 04 15:28:23 loe volumio[31703]: info: Discovery: Found device Loe Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:28:23 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:23 loe volumio[31703]: info: Asound.conf file written Mar 04 15:28:23 loe sudo[31853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 04 15:28:23 loe sudo[31853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:23 loe sudo[31853]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:23 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 04 15:28:23 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 04 15:28:23 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 04 15:28:23 loe volumio[31703]: No state is present for card vc4hdmi Mar 04 15:28:23 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 04 15:28:23 loe volumio[31703]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 04 15:28:23 loe volumio[31703]: Hardware is initialized using a generic method Mar 04 15:28:23 loe volumio[31703]: No state is present for card vc4hdmi Mar 04 15:28:23 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 04 15:28:23 loe volumio[31703]: info: Output device has changed, restarting MPD Mar 04 15:28:23 loe volumio[31703]: info: Output device has changed, restarting Shairport Sync Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:23 loe sudo[31859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 15:28:23 loe sudo[31859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:23 loe sudo[31859]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:23 loe volumio[31703]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 15:28:23 loe volumio[31703]: info: ___________ START PLUGINS ___________ Mar 04 15:28:23 loe sudo[31861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 15:28:23 loe sudo[31861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:23 loe volumio[31703]: info: ControllerMpd::onStart: Initializing MPD Mar 04 15:28:23 loe volumio[31703]: info: Creating MPD Configuration file Mar 04 15:28:23 loe sudo[31871]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 15:28:23 loe sudo[31871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:23 loe systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 15:28:23 loe sudo[31869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 04 15:28:23 loe sudo[31871]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:23 loe sudo[31869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 15:28:23 loe volumio[31703]: info: [1772634503727] CoreMusicLibrary::Adding element Media Servers Mar 04 15:28:23 loe sudo[31874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 15:28:23 loe sudo[31874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:23 loe systemd[1]: mpd.service: Deactivated successfully. Mar 04 15:28:23 loe systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 15:28:23 loe systemd[1]: mpd.service: Consumed 5.312s CPU time. Mar 04 15:28:23 loe systemd[1]: mpd.socket: Deactivated successfully. Mar 04 15:28:23 loe systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 15:28:23 loe systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 15:28:23 loe volumio[31703]: info: UPNP Browser: Client initialized successfully Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:23 loe systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 15:28:23 loe systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 15:28:23 loe sudo[31869]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:23 loe volumio[31703]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:23 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:24 loe systemd[1]: mpd.service: Deactivated successfully. Mar 04 15:28:24 loe systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 15:28:24 loe systemd[1]: mpd.socket: Deactivated successfully. Mar 04 15:28:24 loe systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 15:28:24 loe systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 15:28:24 loe volumio[31703]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 15:28:24 loe volumio[31703]: info: [1772634504071] CoreMusicLibrary::Adding element Last_100 Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 15:28:24 loe volumio[31703]: info: [1772634504104] CoreMusicLibrary::Adding element Webradio Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 15:28:24 loe systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 15:28:24 loe volumio[31703]: info: Initializing BBC Radios Mar 04 15:28:24 loe systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:24 loe volumio[31703]: info: Creating Spotify config file Mar 04 15:28:24 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:24 loe sudo[31889]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 15:28:24 loe sudo[31889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 15:28:24 loe sudo[31889]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:24 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:24 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:24 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:24 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Mar 04 15:28:24 loe volumio-remote-updater[714]: [2026-03-04 15:28:24] [connect] Successful connection Mar 04 15:28:24 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:24 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:24 loe go-librespot[31917]: go-librespot daemon starting... Mar 04 15:28:24 loe go-librespot[31919]: time="2026-03-04T15:28:24+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:25 loe go-librespot[31919]: time="2026-03-04T15:28:25+01:00" level=info msg="zeroconf server listening on port 41003" Mar 04 15:28:25 loe volumio5-onboarding[2840]: time=2026-03-04T15:28:25.776+01:00 level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Mar 04 15:28:26 loe volumio[31703]: info: Volumio Calling Home Mar 04 15:28:26 loe volumio[31814]: Starting albumart workers Mar 04 15:28:26 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:26 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:26 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:26 loe volumio[31815]: Starting albumart workers Mar 04 15:28:27 loe sudo[31931]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 15:28:27 loe sudo[31933]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 15:28:27 loe sudo[31933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:27 loe sudo[31931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:27 loe volumio[31703]: info: MPD Permissions set Mar 04 15:28:27 loe sudo[31933]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:27 loe sudo[31931]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:27 loe volumio[31703]: info: MPD Permissions set Mar 04 15:28:27 loe volumio-remote-updater[714]: [2026-03-04 15:28:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1772634504 101 Mar 04 15:28:27 loe volumio[31703]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Mar 04 15:28:27 loe volumio[31703]: info: Volumio called home Mar 04 15:28:27 loe volumio[31703]: info: Spotify config file written Mar 04 15:28:27 loe sudo[31937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 04 15:28:27 loe volumio[31813]: Starting albumart workers Mar 04 15:28:27 loe sudo[31937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:28 loe systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 04 15:28:28 loe systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 04 15:28:28 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:28 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:28 loe go-librespot[31939]: go-librespot daemon starting... Mar 04 15:28:28 loe sudo[31937]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28:28+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28:28+01:00" level=debug msg="app state loaded" Mar 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:28 loe volumio[31703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:28 loe volumio[31703]: info: No need to fix Spotify hosts Mar 04 15:28:28 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:28 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:28 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28: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 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28: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 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28: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 04 15:28:28 loe go-librespot[31940]: time="2026-03-04T15:28:28+01:00" level=info msg="zeroconf server listening on port 43429" Mar 04 15:28:29 loe go-librespot[31940]: time="2026-03-04T15:28:29+01:00" level=debug msg="obtained new client token: AACmJWIeD9wiFkI/RCkYTQCCzIa5/KjlKXOzJnhXAPWDW6r1v9vomTDpZrL/tISqFGqfWYJFOKmPYqI49mhk2ezHlqeKYiOLJYdaEgpV7+Fs+zaQFEbAYfwfqmGhAawc0WMyvyDEMkeOooUbe1LmS/n20A+kENNMqK3Wf9aZUXd2bQRADJ8UOJZrW+QyDB3hQ4GwPASgqi4gR4DMWtYXD25ZWnI/1c+pEfkLx4qX0ELvYVwMmHkKl14=" Mar 04 15:28:29 loe go-librespot[31940]: time="2026-03-04T15:28:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:29 loe go-librespot[31940]: time="2026-03-04T15:28:29+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:29 loe go-librespot[31940]: time="2026-03-04T15:28:29+01:00" level=debug msg="completed challenge" Mar 04 15:28:29 loe go-librespot[31940]: time="2026-03-04T15:28:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:28:29 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:29 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:29 loe volumio[31703]: info: Starting Shairport Sync Mar 04 15:28:29 loe volumio[31703]: info: Starting Shairport Sync Mar 04 15:28:29 loe volumio[31703]: info: Starting Shairport Sync Mar 04 15:28:29 loe sudo[31968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 15:28:29 loe sudo[31968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:29 loe sudo[31975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 15:28:29 loe sudo[31975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:29 loe volumio[31703]: info: An error occurred while refreshing Spotify Token Error: Bad Request Mar 04 15:28:30 loe sudo[31981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 04 15:28:30 loe sudo[31981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:30 loe systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 15:28:30 loe systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 15:28:30 loe systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 15:28:30 loe systemd[1]: shairport-sync.service: Consumed 5.702s CPU time. Mar 04 15:28:30 loe sudo[31977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 15:28:30 loe sudo[31977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:30 loe systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 15:28:30 loe sudo[31975]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:30 loe sudo[31968]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:30 loe volumio[31703]: info: Shairport-Sync Started Mar 04 15:28:30 loe volumio[31703]: Error adding Membership: Error: addMembership EINVAL Mar 04 15:28:30 loe volumio[31703]: info: Shairport-Sync Started Mar 04 15:28:30 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:28:30 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:28:30 loe systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 15:28:30 loe systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 15:28:30 loe systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 15:28:30 loe sudo[31981]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:30 loe volumio[31703]: info: Upmpdcli Daemon Started Mar 04 15:28:30 loe systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 15:28:30 loe sudo[31977]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:30 loe volumio[31703]: info: Shairport-Sync Started Mar 04 15:28:30 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:30 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:30 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:31 loe mpd[31906]: 2026-03-04T15:28:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 15:28:31 loe systemd[1]: Started mpd.service - Music Player Daemon. Mar 04 15:28:31 loe sudo[31861]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:31 loe sudo[31874]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:31 loe volumio[31703]: info: Completed starting Core Plugins Mar 04 15:28:31 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:31 loe volumio[31703]: info: ----- MyVolumio plugins startup ---- Mar 04 15:28:31 loe volumio[31703]: info: ------------------------------------------- Mar 04 15:28:31 loe volumio[31703]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 04 15:28:31 loe volumio[31703]: error: MPD error: The expression evaluated to a falsy value: Mar 04 15:28:31 loe volumio[31703]: assert.ok(self.idling) Mar 04 15:28:31 loe volumio[31703]: error: The expression evaluated to a falsy value: Mar 04 15:28:31 loe volumio[31703]: assert.ok(self.idling) Mar 04 15:28:31 loe volumio[31703]: info: MPD running with PID31906 Mar 04 15:28:31 loe volumio[31703]: ,establishing connection Mar 04 15:28:31 loe volumio[31703]: error: updateQueue error: null Mar 04 15:28:31 loe volumio[31703]: error: updateQueue error: null Mar 04 15:28:32 loe volumio[31703]: info: go-librespot daemon successfully initialized Mar 04 15:28:32 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 04 15:28:32 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:32 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:32 loe go-librespot[32022]: go-librespot daemon starting... Mar 04 15:28:32 loe go-librespot[32023]: time="2026-03-04T15:28:32+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:32 loe go-librespot[32023]: time="2026-03-04T15:28:32+01:00" level=debug msg="app state loaded" Mar 04 15:28:32 loe go-librespot[32023]: time="2026-03-04T15:28:32+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:32 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:32 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:32 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+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 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+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 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=info msg="zeroconf server listening on port 34359" Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=debug msg="obtained new client token: AADiE10J/WaoWFkc6pZb/PnQcpZpavDjP/6Veo+/hSPMNol/5latIXCr4Scrsq8gPBb5V1hKJqCMVHSgfpQO+OT64PFvqCS51TlAM1sN/MMljPOJQHPfo8bvFryLqxqb4FgJWYsM/9i5FOaCvOgVe39B2HzpPRwQr5YgoB+yNClZe7u2kLAd+ie+79yatDyjBx4Be7FLckZ2+HLgvC7w01Ksv8rj80Qc6bKygHcyvoa6gepYuRe31EE=" Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=debug msg="completed challenge" Mar 04 15:28:33 loe go-librespot[32023]: time="2026-03-04T15:28:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:28:33 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:33 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:34 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:34 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:34 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:35 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:28:35 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:28:36 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 04 15:28:36 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:36 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:36 loe go-librespot[32044]: go-librespot daemon starting... Mar 04 15:28:36 loe go-librespot[32045]: time="2026-03-04T15:28:36+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:36 loe go-librespot[32045]: time="2026-03-04T15:28:36+01:00" level=debug msg="app state loaded" Mar 04 15:28:36 loe go-librespot[32045]: time="2026-03-04T15:28:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:36 loe volumio[31703]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 04 15:28:36 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:36 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:36 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:36 loe go-librespot[32045]: time="2026-03-04T15:28:36+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:36+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:36+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+01:00" level=info msg="zeroconf server listening on port 41737" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+01:00" level=debug msg="obtained new client token: AABBrGFs+1YUg1P3Yo5Ze4YQ5qFQ/rfIGrIL+96y3g6h45KP0ay1f9J461twQdaJxpV7gr84pjrkr/C2QZqIqec5GtXek7KoyH2qU9cGixVsMkculNBGNlJwhKawU/ARJXM2cJlQ6lkph5uw6pM0TB6v101XZ1ew2LBMajSKFMyqjGvBxH/wIPqTns3pbgNpxC+Pt35QrHOhG9XDS2Rw8fyg4AXP9j0faD9GY5i1RjVXcUet7JYLOSI=" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+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 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+01:00" level=debug msg="completed challenge" Mar 04 15:28:37 loe go-librespot[32045]: time="2026-03-04T15:28:37+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 04 15:28:37 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:37 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:38 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:28:38 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:28:38 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:38 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:38 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin bluetooth to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin multiroom to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin metavolumio to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin cd_controller to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 04 15:28:40 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 04 15:28:40 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 04 15:28:40 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:40 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:40 loe go-librespot[32052]: go-librespot daemon starting... Mar 04 15:28:40 loe go-librespot[32053]: time="2026-03-04T15:28:40+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:40 loe go-librespot[32053]: time="2026-03-04T15:28:40+01:00" level=debug msg="app state loaded" Mar 04 15:28:40 loe go-librespot[32053]: time="2026-03-04T15:28:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:40 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:40 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:40 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+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 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+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 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+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 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+01:00" level=info msg="zeroconf server listening on port 35851" Mar 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+01:00" level=debug msg="obtained new client token: AABCy92yZ2Eg1K5v1wwOoTJNuyQm56T6P+QTtHPIwklzOkf3RdukFrhAgoOiwZMOZq9fUoytr/w2YZFIkFbqd1g22B6BN4NtWA5KdEImT+9Oy6K+ibokDrGR3v6ll1kdNZ3yfrAVfXQwSsmq54gwoQhMtTE8Y7mEG8FgwIi26J0KgdImy8amo6RsowqNirlCEg7Sp3rYudzz3IfhaLDhOvnbBnLhAr+tOTZBwgYg29iYUcqeszq3cEo=" Mar 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+01:00" level=debug msg="completed challenge" Mar 04 15:28:41 loe go-librespot[32053]: time="2026-03-04T15:28:41+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 04 15:28:41 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:41 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:42 loe volumio[31703]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 04 15:28:42 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 04 15:28:42 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:42 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:42 loe volumio[31703]: info: Starting MyVolumio Remote Streaming Endpoints Mar 04 15:28:42 loe volumio[31703]: info: MyVolumio login type: Token Mar 04 15:28:42 loe volumio[31703]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 04 15:28:42 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 04 15:28:42 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:42 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:42 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:43 loe volumio[31703]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 04 15:28:43 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 04 15:28:43 loe volumio[31703]: info: Streaming services startup Mar 04 15:28:43 loe volumio[31703]: info: Starting Streaming Daemon Mar 04 15:28:43 loe volumio[31703]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 04 15:28:43 loe sudo[32062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 04 15:28:43 loe sudo[32062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:43 loe sudo[32062]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:43 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:28:43 loe volumio[31703]: error: Cannot start Volumio Streaming Daemon Mar 04 15:28:43 loe volumio[31703]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 04 15:28:43 loe volumio[31703]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 04 15:28:43 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:28:44 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 04 15:28:44 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:44 loe volumio[31703]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 04 15:28:44 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:44 loe go-librespot[32068]: go-librespot daemon starting... Mar 04 15:28:44 loe go-librespot[32069]: time="2026-03-04T15:28:44+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:44 loe go-librespot[32069]: time="2026-03-04T15:28:44+01:00" level=debug msg="app state loaded" Mar 04 15:28:44 loe go-librespot[32069]: time="2026-03-04T15:28:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:44 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:44 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:44 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+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 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+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 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+01:00" level=info msg="zeroconf server listening on port 44889" Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+01:00" level=debug msg="obtained new client token: AAD0RZqXJl8PoNHqwQRCBD2Fn16LtYeLnMWtUAXGw5oXwI4oq9Uhy0TifHSkp9G2DZKWOi7Ns4bttw2lXKSqonw6Ri9jva9FPWdbgweoB5kqOLYxBNVgkrqeNvVLAe/natGD2wDUPN3H/Dr2YrUh6NX8/nE9ZWY30tmX5+oAbctIMiLiK5Ianai9RlSG47y5FpTFE7Vy5GMv2nl0pLwCF/nrHgk9K5fqarFz4ns0xxCR2Xc1tRwZkLY=" Mar 04 15:28:45 loe volumio[31703]: info: MyVolumio token set successfully Mar 04 15:28:45 loe volumio[31703]: info: MYVOLUMIO: Adding device Mar 04 15:28:45 loe volumio[31703]: info: MYVOLUMIO: Evaluating Server Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+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 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+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 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+01:00" level=debug msg="completed challenge" Mar 04 15:28:45 loe go-librespot[32069]: time="2026-03-04T15:28:45+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 04 15:28:45 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:45 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:45 loe volumio[31703]: info: MyVolumio Plan changed: premium Mar 04 15:28:45 loe volumio[31703]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 04 15:28:45 loe volumio[31703]: info: Removing browser output: myVolumio user plan is not superstar Mar 04 15:28:45 loe volumio[31703]: info: Removing audio output: Mar 04 15:28:45 loe volumio[31703]: info: MYVOLUMIO: Adding device Mar 04 15:28:45 loe volumio[31703]: info: MYVOLUMIO: Evaluating Server Mar 04 15:28:45 loe volumio[31703]: info: Remote config written successfully Mar 04 15:28:45 loe volumio[31703]: info: Starting Tunnel 1 Mar 04 15:28:45 loe volumio[31703]: info: Starting Tunnel Connection Checker Mar 04 15:28:45 loe volumio[31703]: info: Completed starting MyVolumio Plugin Mar 04 15:28:46 loe volumio[31703]: info: MYVolumio Device enabled Mar 04 15:28:46 loe volumio[31703]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 04 15:28:46 loe volumio[31703]: info: MyVolumio status changed Mar 04 15:28:46 loe volumio[31703]: info: Streaming services startup Mar 04 15:28:46 loe volumio[31703]: info: Starting Streaming Daemon Mar 04 15:28:46 loe sudo[32131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 04 15:28:46 loe sudo[32131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:46 loe volumio[31703]: info: Setting Geolocation for MyVolumio to eu12 Mar 04 15:28:46 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:46 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:46 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:46 loe sudo[32131]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:46 loe volumio[31703]: error: Cannot start Volumio Streaming Daemon Mar 04 15:28:46 loe volumio[31703]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 04 15:28:46 loe volumio[31703]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 04 15:28:46 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 04 15:28:46 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 04 15:28:46 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:46 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:46 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:46 loe volumio[31703]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 04 15:28:46 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 04 15:28:48 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:48 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:48 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:48 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 04 15:28:48 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:48 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:48 loe go-librespot[32133]: go-librespot daemon starting... Mar 04 15:28:48 loe go-librespot[32134]: time="2026-03-04T15:28:48+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:48 loe go-librespot[32134]: time="2026-03-04T15:28:48+01:00" level=debug msg="app state loaded" Mar 04 15:28:48 loe go-librespot[32134]: time="2026-03-04T15:28:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 04 15:28:49 loe sudo[32142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 04 15:28:49 loe sudo[32142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:49 loe sudo[32142]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:49 loe volumio[31703]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 04 15:28:49 loe volumio[31703]: info: MRS: MultiRoom plugin initialized Mar 04 15:28:49 loe volumio[31703]: info: MRS: STOPPING SNAPCLIENT Mar 04 15:28:49 loe volumio[31703]: info: MRS: Snap server stop Mar 04 15:28:49 loe volumio[31703]: info: MRS: STOPPING volumioStreaming Mar 04 15:28:49 loe sudo[32161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 04 15:28:49 loe sudo[32161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 04 15:28:49 loe sudo[32165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 04 15:28:49 loe sudo[32165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:49 loe sudo[32163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28: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 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28: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 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28: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 04 15:28:49 loe sudo[32163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:49 loe sudo[32168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 04 15:28:49 loe sudo[32168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28:49+01:00" level=info msg="zeroconf server listening on port 36549" Mar 04 15:28:49 loe sudo[32168]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 04 15:28:49 loe sudo[32161]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28:49+01:00" level=debug msg="obtained new client token: AAB6J5ZWv5amosfGsRoBwNoDxJmw1eqnvF9Y5RuNAV4SrqeGEkYAzizDhqdYK05tO0bdgpMj4wxB3oatUK8QXHx5V9bfg+uvP2+r0RY44pU5LhSn3Xt0sZZtfQQs5moR23XwbLNT/iEhgVKAkXj8gZxtjKjczGnRIzGOb0jPm5Kghq/96ttkPDzbiEFAh6MNqctcSR0tOdURTKJr5e4Cpa3lt6QFgHSPTAZTYhcbsuwJFTf4vDcVYOEF3w==" Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 04 15:28:49 loe sudo[32165]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:49 loe sudo[32163]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28:49+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28:49+01:00" level=debug msg="completed challenge" Mar 04 15:28:49 loe volumio[31703]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 04 15:28:49 loe go-librespot[32134]: time="2026-03-04T15:28: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 04 15:28:49 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:49 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:49 loe volumio[31703]: info: Preparing to generate the ALSA configuration file Mar 04 15:28:49 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:28:49 loe volumio[31703]: info: Updating MyVolumio device info Mar 04 15:28:49 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:49 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:49 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:49 loe volumio[31703]: info: Setting Geolocation for MyVolumio to eu8 Mar 04 15:28:49 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:50 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:50 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:50 loe volumio[31703]: info: Successfully Added MyVolumio device Mar 04 15:28:50 loe volumio[31703]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 04 15:28:50 loe volumio[31703]: info: Reading ALSA contributions from plugins. Mar 04 15:28:50 loe volumio[31703]: info: MRS: Removed streaming files Mar 04 15:28:50 loe volumio[31703]: info: MRS: volumioStreaming STOPPED Mar 04 15:28:50 loe volumio[31703]: info: MRS: SNAPSERVER STOPPED Mar 04 15:28:50 loe volumio[31703]: info: MRS: SNAPCLIENT STOPPED Mar 04 15:28:50 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:28:50 loe volumio[31703]: info: Successfully Updated MyVolumio device Mar 04 15:28:50 loe volumio[31703]: info: Asound.conf file written Mar 04 15:28:50 loe sudo[32177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 04 15:28:50 loe sudo[32177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:50 loe sudo[32177]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:50 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 04 15:28:50 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 04 15:28:50 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 04 15:28:50 loe volumio[31703]: No state is present for card vc4hdmi Mar 04 15:28:50 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 04 15:28:50 loe volumio[31703]: Found hardware: "vc4-hdmi" "" "" "" "" Mar 04 15:28:50 loe volumio[31703]: Hardware is initialized using a generic method Mar 04 15:28:50 loe volumio[31703]: No state is present for card vc4hdmi Mar 04 15:28:50 loe volumio[31703]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 04 15:28:50 loe volumio[31703]: info: Output device has changed, restarting MPD Mar 04 15:28:50 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:50 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:50 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:50 loe volumio[31703]: info: Output device has changed, restarting Shairport Sync Mar 04 15:28:50 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:50 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:50 loe sudo[32185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 04 15:28:50 loe sudo[32185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:50 loe sudo[32187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 04 15:28:50 loe sudo[32185]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:50 loe sudo[32187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:50 loe volumio[31703]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 04 15:28:50 loe volumio[31703]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 04 15:28:50 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:50 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 04 15:28:50 loe volumio[31703]: ------------------------------------ BT MESSAGE: [FUNC] onStart Mar 04 15:28:50 loe volumio[31703]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Mar 04 15:28:50 loe volumio[31703]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Mar 04 15:28:50 loe volumio[31703]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Mar 04 15:28:50 loe systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 04 15:28:50 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 04 15:28:50 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 04 15:28:50 loe volumio[31703]: info: MRS: Pushing multiroomSync output for this device Mar 04 15:28:50 loe volumio[31703]: info: MRS: Pushing multiroomSync output Mar 04 15:28:50 loe volumio[31703]: info: Adding audio output: Mar 04 15:28:50 loe volumio[31703]: info: Adding audio output: Mar 04 15:28:51 loe volumio[31703]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 04 15:28:51 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:51 loe systemd[1]: mpd.service: Deactivated successfully. Mar 04 15:28:51 loe systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 04 15:28:51 loe systemd[1]: mpd.service: Consumed 6.684s CPU time. Mar 04 15:28:51 loe systemd[1]: mpd.socket: Deactivated successfully. Mar 04 15:28:51 loe systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 04 15:28:51 loe systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 04 15:28:51 loe bluetoothd[980]: Path / reserved for Adv Monitor app :1.8857 Mar 04 15:28:51 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 04 15:28:51 loe volumio[31703]: info: Adding METAVOLUMIO REST API Endpoints Mar 04 15:28:51 loe volumio[31703]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 04 15:28:51 loe volumio[31703]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 04 15:28:51 loe volumio[31703]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 04 15:28:51 loe volumio[31703]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 04 15:28:51 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 04 15:28:51 loe bluetoothd[980]: Adv Monitor app :1.8857 disconnected from D-Bus Mar 04 15:28:51 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 04 15:28:51 loe volumio[31703]: info: Preparing CD Folders Mar 04 15:28:51 loe volumio[31703]: info: Adding CD REST API Endpoints Mar 04 15:28:51 loe volumio[31703]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 04 15:28:51 loe volumio[31703]: info: Starting UDEV Watcher for CD Mar 04 15:28:51 loe volumio[31703]: info: Detecting CD presence with UDEV Mar 04 15:28:51 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 04 15:28:51 loe systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 04 15:28:51 loe systemd[1]: Starting mpd.service - Music Player Daemon... Mar 04 15:28:51 loe sudo[32202]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 04 15:28:51 loe sudo[32202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 15:28:51 loe sudo[32202]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:52 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:52 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:52 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:53 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Mar 04 15:28:53 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:53 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:53 loe go-librespot[32208]: go-librespot daemon starting... Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=debug msg="app state loaded" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+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 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+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 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=info msg="zeroconf server listening on port 46287" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=debug msg="obtained new client token: AAAvGWl4UYapTEvOafQ99LcY447MzS5b5ScdYs3l/wpIshI7g04jkdH6Pwsu8lbiTH2UYNRlK6wNTO+w87/Kum91qYiWcGxftJy2Y7t2NhVhvR6EWjcodgEM2d0l7Vr9LcAQBmrf0XQ1BU5vXNIe7FFaEXg2umLzH63SibyLxgZ50IKMEXtLUohTeQ/L82I/9v9owH0+WbEQtc8cxGW5ToL/snOt1gNNtSHHnQq2hp0hEA6QJsZwhsIXzw==" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28:53+01:00" level=debug msg="completed challenge" Mar 04 15:28:53 loe go-librespot[32209]: time="2026-03-04T15:28: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 04 15:28:53 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:53 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:54 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:54 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:54 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:56 loe volumio[31703]: warn: [cd-plugin] cdspeedctl: device or media not ready Mar 04 15:28:56 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 04 15:28:56 loe volumio[31703]: info: Adding inputs REST Endpoints Mar 04 15:28:56 loe volumio[31703]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 04 15:28:56 loe volumio[31703]: info: Scanning Audio Inputs Mar 04 15:28:56 loe volumio[31703]: info: Checking against Known Cards name Mar 04 15:28:56 loe volumio[31703]: info: Adding Server instance for streaming Mar 04 15:28:56 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 04 15:28:56 loe volumio[31703]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 04 15:28:56 loe volumio[31703]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 04 15:28:56 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 04 15:28:56 loe volumio[31703]: info: Refreshing TIDAL token Mar 04 15:28:56 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 04 15:28:56 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 04 15:28:56 loe volumio[31703]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Mar 04 15:28:56 loe volumio[31703]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Mar 04 15:28:56 loe volumio[31703]: info: QobuzConnect: Starting Qobuz Connect socket and service Mar 04 15:28:56 loe sudo[32234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 04 15:28:56 loe sudo[32234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:56 loe volumio[31703]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Mar 04 15:28:56 loe volumio[31703]: info: Stopping AccessToken refresher cron for QOBUZ Mar 04 15:28:56 loe sudo[32240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 04 15:28:56 loe sudo[32240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:56 loe sudo[32240]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:56 loe systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Mar 04 15:28:56 loe volumio[31703]: info: AccessToken refresher cron started for QOBUZ Mar 04 15:28:56 loe volumio[31703]: info: Adding QOBUZ REST API Endpoints Mar 04 15:28:56 loe systemd[1]: vtcs.service: Killing process 30773 (vtcs) with signal SIGKILL. Mar 04 15:28:56 loe systemd[1]: vtcs.service: Deactivated successfully. Mar 04 15:28:56 loe systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Mar 04 15:28:56 loe volumio[31703]: info: MRS: Getting audio outputs on start Mar 04 15:28:56 loe volumio[31703]: info: MRS: Requesting all other devices output Mar 04 15:28:56 loe volumio[31703]: info: Updating MyVolumio device info Mar 04 15:28:56 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:56 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:56 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:56 loe sudo[32234]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:56 loe mpd[32207]: 2026-03-04T15:28:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 04 15:28:56 loe systemd[1]: Started mpd.service - Music Player Daemon. Mar 04 15:28:56 loe sudo[32243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 04 15:28:56 loe sudo[32243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:56 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 15:28:56 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:56 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 15:28:56 loe sudo[32187]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:56 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:28:56 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:56 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:56 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:56 loe systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Mar 04 15:28:56 loe qobuz-connect[30715]: 20260304 15:28:56.781 [30715.30715] INFO SampleApp: Stopping Local configuration server Mar 04 15:28:56 loe volumio[31703]: info: Successfully Added MyVolumio device Mar 04 15:28:56 loe volumio[31703]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Mar 04 15:28:56 loe volumio[31703]: info: MPD Permissions set Mar 04 15:28:56 loe sudo[32253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 04 15:28:56 loe sudo[32253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:57 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Mar 04 15:28:57 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:57 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:28:57 loe go-librespot[32255]: go-librespot daemon starting... Mar 04 15:28:57 loe systemd[1]: qobuz-connect.service: Deactivated successfully. Mar 04 15:28:57 loe systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 15:28:57 loe systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Mar 04 15:28:57 loe sudo[32243]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:57 loe sudo[32253]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:57 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+01:00" level=debug msg="app state loaded" Mar 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:28:57 loe volumio[31703]: info: Successfully Updated MyVolumio device Mar 04 15:28:57 loe volumio[31703]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::volumioStop Mar 04 15:28:57 loe volumio[31703]: info: CoreStateMachine::stop Mar 04 15:28:57 loe volumio[31703]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 15:28:57 loe volumio[31703]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Mar 04 15:28:57 loe volumio[31703]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+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 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+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 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+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 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 04 15:28:57 loe volumio-remote-updater[714]: Test mode disabled Mar 04 15:28:57 loe volumio-remote-updater[714]: Alpha mode disabled Mar 04 15:28:57 loe volumio-remote-updater[714]: Alpha legacy test mode disabled Mar 04 15:28:57 loe volumio[31703]: info: Access Token successfully retrieved Mar 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+01:00" level=info msg="zeroconf server listening on port 36011" Mar 04 15:28:57 loe volumio[31703]: error: Hi Res Audio Failed Login: [object Object] Mar 04 15:28:57 loe volumio[31703]: error: updateQueue error: null Mar 04 15:28:57 loe volumio[31703]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 04 15:28:57 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 04 15:28:57 loe volumio[31703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 04 15:28:57 loe sudo[32277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 04 15:28:57 loe sudo[32277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:57 loe go-librespot[32256]: time="2026-03-04T15:28:57+01:00" level=debug msg="obtained new client token: AAAOYHH6aOOh7/IEmy3SYKjRTOcqQC3haqSg3+Th7MBPZTMM/NeKWtxe6EbEy0LzBUqtbhnEkZ98oz2plM53uq+xpAgIhRpk/fcYDMNGIKI0Unot+Eik/EqJaZV3nMt9xs8anKpxlTu175wRnfzG9iOGgJ8nZyJgUcJk230KTseqOuvlcCMmWzDwEikCUHcmSrKGk4fiUq5lSn2SXc57VmUDTrxT6HXUNDkuqFyv5yQmDAk76ozN390P4w==" Mar 04 15:28:58 loe autossh[30725]: received signal to exit (15) Mar 04 15:28:58 loe go-librespot[32256]: time="2026-03-04T15:28:58+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:28:58 loe systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Mar 04 15:28:58 loe volumio[31703]: info: Executing endpoint qc_getconfig Mar 04 15:28:58 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 04 15:28:58 loe systemd[1]: sshtunnel.service: Deactivated successfully. Mar 04 15:28:58 loe systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Mar 04 15:28:58 loe go-librespot[32256]: time="2026-03-04T15:28:58+01:00" level=debug msg="completed keyexchange" Mar 04 15:28:58 loe go-librespot[32256]: time="2026-03-04T15:28:58+01:00" level=debug msg="completed challenge" Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.123 [32259.32259] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.137 [32259.32259] INFO VolumeManager: [0x151f408]: Setting new playback volume: 75 Mar 04 15:28:58 loe volumio[31703]: info: Starting Shairport Sync Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.138 [32259.32259] INFO VolumeManager: [0x151f408]: Setting new mute state: 0 Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.138 [32259.32259] INFO QobuzConnect: [0x151fdd8]: Client initialized! Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.138 [32259.32259] INFO SampleApp: Starting Avahi advertising, name: Loe, service name: _qobuz-connect._tcp Mar 04 15:28:58 loe go-librespot[32256]: time="2026-03-04T15:28:58+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:28:58 loe systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Mar 04 15:28:58 loe volumio[31703]: info: MRS: Found cast device: Chromecast-84d0dcfc86c31e08fb7722859cd1fe00 Mar 04 15:28:58 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:28:58 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:28:58 loe volumio[31703]: info: Adding audio output: Mar 04 15:28:58 loe autossh[32283]: port set to 0, monitoring disabled Mar 04 15:28:58 loe autossh[32283]: starting ssh (count 1) Mar 04 15:28:58 loe autossh[32283]: ssh child pid is 32289 Mar 04 15:28:58 loe sudo[32277]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.259 [32259.32259] INFO LocalConfigManager: [0x151ece8]: Starting Local Configuration server Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.259 [32259.32259] INFO SampleApp: Starting Local configuration server Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.260 [32259.32259] INFO SampleApp: Connected to UNIX socket client 0x1509818 Mar 04 15:28:58 loe sudo[32287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 04 15:28:58 loe sudo[32287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:58 loe qobuz-connect[32259]: 20260304 15:28:58.273 [32259.32259] INFO SampleApp: Playback volume changed: 75 Mar 04 15:28:58 loe volumio[31703]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 04 15:28:58 loe volumio[31703]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 04 15:28:58 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:28:58 loe volumio[31703]: info: Remote SSH Started Mar 04 15:28:58 loe systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 04 15:28:58 loe systemd[1]: shairport-sync.service: Deactivated successfully. Mar 04 15:28:58 loe systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 15:28:58 loe systemd[1]: shairport-sync.service: Consumed 1.984s CPU time. Mar 04 15:28:58 loe systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 04 15:28:58 loe sudo[32287]: pam_unix(sudo:session): session closed for user root Mar 04 15:28:58 loe volumiossh-tunnel[32289]: Warning: Permanently added '[eu8.myvolumio.org]:2222' (RSA) to the list of known hosts. Mar 04 15:28:58 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:28:58 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:28:58 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:28:58 loe volumio[31703]: info: Successfully retrieved User Session From TIDAL Mar 04 15:28:58 loe volumio[31703]: info: Shairport-Sync Started Mar 04 15:28:59 loe volumio[31703]: 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 04 15:28:59 loe volumio[31703]: info: Successfully retrieved User Subscription From TIDAL Mar 04 15:28:59 loe volumio[31703]: info: Adding TIDAL to Browse Sources Mar 04 15:28:59 loe volumio[31703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 04 15:28:59 loe volumio[31703]: info: [1772634539149] CoreMusicLibrary::Adding element TIDAL Mar 04 15:28:59 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 15:28:59 loe volumio[31703]: Cannot find translation for source TIDAL Mar 04 15:28:59 loe volumio[31703]: info: Adding TIDAL REST API Endpoints Mar 04 15:28:59 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:28:59 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:28:59 loe volumio[31703]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.mjORZRjMx8YIlyGajvB2CXSYA5r2.a53384b16fd4d7a180fdac1b5068092c.state.status' Mar 04 15:28:59 loe volumio[31703]: info: TidalConnect service stoped! Mar 04 15:28:59 loe volumio[31703]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 04 15:28:59 loe volumio[31703]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 04 15:28:59 loe sudo[32317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 04 15:28:59 loe sudo[32317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 15:28:59 loe systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Mar 04 15:28:59 loe sudo[32317]: pam_unix(sudo:session): session closed for user root Mar 04 15:29:00 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:00 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:00 loe volumio[31703]: info: Executing endpoint tc_getconfig Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 04 15:29:00 loe vtcs[32320]: STARTING TidalConnect services, version: 1.6.1 Mar 04 15:29:00 loe vtcs[32320]: STARTED TidalConnect services. Mar 04 15:29:00 loe volumio[31703]: info: Executing endpoint tc_connect Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 04 15:29:00 loe volumio[31703]: info: Connecting to TidalConnect Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::servicePushState Mar 04 15:29:00 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:29:00 loe volumio[31703]: info: MRS: Pushing multiroomSync output update for this device Mar 04 15:29:00 loe volumio[31703]: info: MRS: Pushing multiroomSync output Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:29:00 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:29:00 loe volumio[31703]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::servicePushState Mar 04 15:29:00 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:29:00 loe volumio[31703]: info: MRS: Pushing multiroomSync output update for this device Mar 04 15:29:00 loe volumio[31703]: info: MRS: Pushing multiroomSync output Mar 04 15:29:00 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:29:00 loe volumio[31703]: info: CorePlayQueue::getTrack 0 Mar 04 15:29:00 loe volumio[31703]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Mar 04 15:29:00 loe volumio[31703]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.mjORZRjMx8YIlyGajvB2CXSYA5r2.a53384b16fd4d7a180fdac1b5068092c.state.status' Mar 04 15:29:00 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:00 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:00 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:01 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Mar 04 15:29:01 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:01 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:01 loe go-librespot[32336]: go-librespot daemon starting... Mar 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+01:00" level=debug msg="app state loaded" Mar 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+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 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+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 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+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 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+01:00" level=info msg="zeroconf server listening on port 39567" Mar 04 15:29:01 loe go-librespot[32337]: time="2026-03-04T15:29:01+01:00" level=debug msg="obtained new client token: AADEPRgR/4EiLQky09Ljn0flpLkYX5aoqljIGVLXmhPk7be8TcdYwqB25OmF7Zpt/rnUmO0LBWZtVlVjHfduXmYMW1WndjNrDukGuCkG8a0RmjvWOGqU9Q4M/2cL4c4t7ONtaho90/4J/78vmxiFBgLAx3GrOJWo8NZ9yZMSoA67ofvrobu9XNfxmT/e2h5/KvlxO+ZYgAFsoYZAIqIqF3nU8cn+/ltBTZUwDRSbtztRAFNPz4yqqyFKeg==" Mar 04 15:29:02 loe go-librespot[32337]: time="2026-03-04T15:29:02+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 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 15:29:02 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 15:29:02 loe go-librespot[32337]: time="2026-03-04T15:29:02+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 04 15:29:02 loe go-librespot[32337]: time="2026-03-04T15:29:02+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:02 loe go-librespot[32337]: time="2026-03-04T15:29:02+01:00" level=debug msg="completed challenge" Mar 04 15:29:02 loe go-librespot[32337]: time="2026-03-04T15:29:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:02 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:02 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:02 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:02 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:02 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:03 loe volumio[31703]: info: TidalConnect service started! Mar 04 15:29:03 loe volumio[31703]: [Metrics] CommandRouter: 52s 73.66ms Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::volumiosetStartupVolume Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 15:29:03 loe volumio[31703]: info: VolumeController:: Setting startup Volume 5 Mar 04 15:29:03 loe volumio[31703]: info: VolumeController::SetAlsaVolume5 Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::Close All Modals sent Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::Close All Modals sent Mar 04 15:29:03 loe volumio[31703]: info: CoreStateMachine::pushState Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::volumioPushState Mar 04 15:29:03 loe volumio[31703]: info: MRS: Pushing multiroomSync output update for this device Mar 04 15:29:03 loe volumio[31703]: info: MRS: Pushing multiroomSync output Mar 04 15:29:03 loe volumio[31703]: info: CoreCommandRouter::volumioGetState Mar 04 15:29:03 loe volumio[31703]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.mjORZRjMx8YIlyGajvB2CXSYA5r2.a53384b16fd4d7a180fdac1b5068092c.state.status' Mar 04 15:29:03 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:03 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:04 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 04 15:29:04 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 15:29:04 loe volumio[31703]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 04 15:29:04 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:04 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:04 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:05 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Mar 04 15:29:05 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:05 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:05 loe go-librespot[32362]: go-librespot daemon starting... Mar 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+01:00" level=debug msg="app state loaded" Mar 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+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 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+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 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+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 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+01:00" level=info msg="zeroconf server listening on port 34073" Mar 04 15:29:05 loe go-librespot[32363]: time="2026-03-04T15:29:05+01:00" level=debug msg="obtained new client token: AADAoUmz+MdQ34HfLYGjIjKh6/kRKyIYVv6B+i9TDWF57yFObfB4nzSr7+IT+oyOAUg1yyLcM2njmpMrjUmerxLHy7OimD5SdBtkZpXNp07ipaIEQGvaIfrXK51BSa0QeBLt6hGzwusL4MRX/uUqvB7Khsh1trghH75i2nn8A/s0K70psFLKD/pw3NrWS7/MIaHLqF+3UKpsVM+R+1tyfxRJaJTYXpRfhjZIcLqD2wsv/x9CsdG6nfqzZQ==" Mar 04 15:29:06 loe go-librespot[32363]: time="2026-03-04T15:29:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:06 loe go-librespot[32363]: time="2026-03-04T15:29:06+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:06 loe go-librespot[32363]: time="2026-03-04T15:29:06+01:00" level=debug msg="completed challenge" Mar 04 15:29:06 loe go-librespot[32363]: time="2026-03-04T15:29:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:06 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:06 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:06 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:06 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:06 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:06 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:06 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:08 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:08 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:08 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:09 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:09 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Mar 04 15:29:09 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:09 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:09 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:09 loe go-librespot[32370]: go-librespot daemon starting... Mar 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+01:00" level=debug msg="app state loaded" Mar 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+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 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+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 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+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 04 15:29:09 loe go-librespot[32371]: time="2026-03-04T15:29:09+01:00" level=info msg="zeroconf server listening on port 43597" Mar 04 15:29:10 loe go-librespot[32371]: time="2026-03-04T15:29:10+01:00" level=debug msg="obtained new client token: AACEo4im9kbdsET6SargLjFYP4LWToi9kz92HsRA97j0UCARqfYAzqYNJWKv6sRSEki6nc1asibMWCDI8I3MFxEqtDFz3AJH1UZ2fcmZ3UDh8VD6snGIvZfqKbcUg4LwX7g3NiE3j6QrXP99euZ2fmokG4IWeNE55Hrx7jItOt8AHjxY3n0BqawaZFZOJzk9ITSugw207lXHa61gLSbwdqWtgN6i8xrX8z24v4OGI5eY7xZ8Q1irVKA=" Mar 04 15:29:10 loe volumio[31703]: info: BOOT COMPLETED Mar 04 15:29:10 loe go-librespot[32371]: time="2026-03-04T15:29:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:10 loe go-librespot[32371]: time="2026-03-04T15:29:10+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:10 loe go-librespot[32371]: time="2026-03-04T15:29:10+01:00" level=debug msg="completed challenge" Mar 04 15:29:10 loe go-librespot[32371]: time="2026-03-04T15:29: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 04 15:29:10 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:10 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:10 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:10 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:10 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:12 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:12 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:12 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:12 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:12 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:13 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Mar 04 15:29:13 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:13 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:13 loe go-librespot[32378]: go-librespot daemon starting... Mar 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+01:00" level=debug msg="app state loaded" Mar 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+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 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+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 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+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 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+01:00" level=info msg="zeroconf server listening on port 44735" Mar 04 15:29:13 loe go-librespot[32379]: time="2026-03-04T15:29:13+01:00" level=debug msg="obtained new client token: AABa+DKFqjM3ZCTl2b/+TnGPIFncENrgsKWikbK+QyOYXx3JcF8aDvJMBrrJjzyuzOc+y0wMAb/41d53SGkfdRAmRBgR7G/39/B3lmCvV+1OJleU190jc8RDhfbaTca8lOE27ybnV8ZVxGj1hK7UjWstXlUe2GlXlgEKkSXLb2I6AyiVOx/nS25CCntj0q6Q10lq2MywxPSayUDUcDnj+5RwR5A+EN8ZLzmLJDDti1r1d1hui2hBRe2pbQ==" Mar 04 15:29:14 loe go-librespot[32379]: time="2026-03-04T15:29:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:14 loe go-librespot[32379]: time="2026-03-04T15:29:14+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:14 loe go-librespot[32379]: time="2026-03-04T15:29:14+01:00" level=debug msg="completed challenge" Mar 04 15:29:14 loe go-librespot[32379]: time="2026-03-04T15:29: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 04 15:29:14 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:14 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:14 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:14 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:14 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:15 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:15 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:16 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:16 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:16 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:17 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Mar 04 15:29:17 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:17 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:17 loe go-librespot[32400]: go-librespot daemon starting... Mar 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+01:00" level=debug msg="app state loaded" Mar 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29: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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+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 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+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 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+01:00" level=info msg="zeroconf server listening on port 34081" Mar 04 15:29:17 loe go-librespot[32401]: time="2026-03-04T15:29:17+01:00" level=debug msg="obtained new client token: AAD6gYAitAsqMmaSLGo6Q/d9JRmg3MeSEwdLAUaT4vpKjE/Uq8vkF9H5sNzK7p0CL+B/LE3I87Q65Bh8LGw9R5yrkZj1G7Aw+AqtKGO585kJNRufeFbTuBuMx9zsVuN/4oTNeLcKxqClrS/H1bh3LtGEkeMYwt5UiidYQ2B9e8fY/7tvlrDF+yqoFItuiblDDVtJh/aorUaKAnZDihXqHlfl1z3iqBGCHwjQuV8w45UnXaSMTeVqiPCHvA==" Mar 04 15:29:18 loe go-librespot[32401]: time="2026-03-04T15:29:18+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:18 loe go-librespot[32401]: time="2026-03-04T15:29:18+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:18 loe go-librespot[32401]: time="2026-03-04T15:29:18+01:00" level=debug msg="completed challenge" Mar 04 15:29:18 loe go-librespot[32401]: time="2026-03-04T15:29: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 04 15:29:18 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:18 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:18 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:18 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:18 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:18 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:18 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:20 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:20 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:20 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:21 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Mar 04 15:29:21 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:21 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:21 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:21 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:21 loe go-librespot[32409]: go-librespot daemon starting... Mar 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29:21+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29:21+01:00" level=debug msg="app state loaded" Mar 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29:21+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 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29:21+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 04 15:29:21 loe go-librespot[32410]: time="2026-03-04T15:29:21+01:00" level=info msg="zeroconf server listening on port 43699" Mar 04 15:29:22 loe go-librespot[32410]: time="2026-03-04T15:29:22+01:00" level=debug msg="obtained new client token: AAAP1HUFS/kKbkSk7Pcbo63q3jeeg3099+y87rt4iLG6Xej/JElzvNDBVCtQSXDeyvckc+OlG1v4etRtW+8SecDdBLfLj1uP649TV6lplOvbHNCrKZJhm8tqAR2fIcerj4AIzWqnDQcVTF0L8+Ryzty3kVTPwtyzyiFDLqlldtOu9gak3NcnT+r66YjvbNP9kh/OCs3tpQO017U/oe4k9dePbcKpMQmGx08bP3Aa6F0QPN48wYtPu+w=" Mar 04 15:29:22 loe go-librespot[32410]: time="2026-03-04T15:29:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:22 loe go-librespot[32410]: time="2026-03-04T15:29:22+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:22 loe go-librespot[32410]: time="2026-03-04T15:29:22+01:00" level=debug msg="completed challenge" Mar 04 15:29:22 loe go-librespot[32410]: time="2026-03-04T15:29:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:22 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:22 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:22 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:22 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:22 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:24 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:24 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:24 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:24 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:24 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:25 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Mar 04 15:29:25 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:25 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:25 loe go-librespot[32432]: go-librespot daemon starting... Mar 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+01:00" level=debug msg="app state loaded" Mar 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+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 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+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 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+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 04 15:29:25 loe go-librespot[32433]: time="2026-03-04T15:29:25+01:00" level=info msg="zeroconf server listening on port 38781" Mar 04 15:29:26 loe go-librespot[32433]: time="2026-03-04T15:29:26+01:00" level=debug msg="obtained new client token: AAAM7Nz04hxsbjO07Adz819TjbTlZuTJw6aXkmOcUSGKa37isba0Fmjyq97E4akc5MQFgH3p/NrEHD7/Mu3IlDpKhWVXHU2qWdmzO+yh3UrUId/1pfwKXQGshId/p3gN5XZFSECBikKOlQAnX+49BL1T/PAXwTREg+42UHAq8Xb0TTXdWLwxNuXs8+dFMr+nSEdFnJtqxjmHyv/FdWiucBOWZF7byBv67LeHh1vEfQwv1Nw1QuKKSRlYFA==" Mar 04 15:29:26 loe go-librespot[32433]: time="2026-03-04T15:29:26+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:26 loe go-librespot[32433]: time="2026-03-04T15:29:26+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:26 loe go-librespot[32433]: time="2026-03-04T15:29:26+01:00" level=debug msg="completed challenge" Mar 04 15:29:26 loe go-librespot[32433]: time="2026-03-04T15:29:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:26 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:26 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:26 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:26 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:26 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:27 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:27 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:28 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:28 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:28 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:29 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Mar 04 15:29:29 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:29 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:29 loe go-librespot[32441]: go-librespot daemon starting... Mar 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+01:00" level=debug msg="app state loaded" Mar 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+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 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+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 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+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 04 15:29:29 loe go-librespot[32442]: time="2026-03-04T15:29:29+01:00" level=info msg="zeroconf server listening on port 42523" Mar 04 15:29:30 loe go-librespot[32442]: time="2026-03-04T15:29:30+01:00" level=debug msg="obtained new client token: AACUejFd/lIdtNPuKCBwu5kut0g9OZCf2b0jG4OcLKfXr18kxBo3agLma2mp39f9HRkWQu39GImZSqubzLrJeZPnMBDgbJCrBEZz51cQ1wJEs+ZTaX/iKqtpdWkl4A/V8C9xWK+Qmf9QXR47/45GPWTiKYRkblBwpz57uljE4IVveZOofOsT3TFIf6cWROOywYNKQNQl/ktaQCvUhIMUt9HXK6muPNxVQ2uUYfjgMJEyjpJ45K12OpE=" Mar 04 15:29:30 loe go-librespot[32442]: time="2026-03-04T15:29:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:30 loe go-librespot[32442]: time="2026-03-04T15:29:30+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:30 loe go-librespot[32442]: time="2026-03-04T15:29:30+01:00" level=debug msg="completed challenge" Mar 04 15:29:30 loe go-librespot[32442]: time="2026-03-04T15:29:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:30 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:30 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:30 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:30 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:30 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:30 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:30 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:32 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:32 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:32 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:33 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:33 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:33 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Mar 04 15:29:33 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:33 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:33 loe go-librespot[32451]: go-librespot daemon starting... Mar 04 15:29:33 loe go-librespot[32452]: time="2026-03-04T15:29:33+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:33 loe go-librespot[32452]: time="2026-03-04T15:29:33+01:00" level=debug msg="app state loaded" Mar 04 15:29:33 loe go-librespot[32452]: time="2026-03-04T15:29:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+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 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+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 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=info msg="zeroconf server listening on port 46449" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=debug msg="obtained new client token: AACpQHFpH9B2uPD6LX7P9+DURoOufGYI9eKN0JSc/7vAUBhGhmcObMOOhEAIfTDa1JzED1VpIqm2aKWs6OIiOtjrEFDxQz2nc3yGw+xZsm59mg5kyNIRWYcZ+6WrZpjrnlhLqkeKhu7quWgQVPgdyy7UGS6OevwiIAJD32nAUSHZZgY8qaA31Fn2xIz4FsppkiKvu38Zplq30N1WtXguE0n8HgM/cKuT0mzAURbypw+NR6vBkEzc/xQ=" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=debug msg="completed challenge" Mar 04 15:29:34 loe go-librespot[32452]: time="2026-03-04T15:29:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:34 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:34 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:34 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:34 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:34 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:36 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:36 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:36 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:36 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:36 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:37 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Mar 04 15:29:37 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:37 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:37 loe go-librespot[32473]: go-librespot daemon starting... Mar 04 15:29:37 loe go-librespot[32474]: time="2026-03-04T15:29:37+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:37 loe go-librespot[32474]: time="2026-03-04T15:29:37+01:00" level=debug msg="app state loaded" Mar 04 15:29:37 loe go-librespot[32474]: time="2026-03-04T15:29:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+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 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+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 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+01:00" level=info msg="zeroconf server listening on port 42269" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+01:00" level=debug msg="obtained new client token: AADFb4siXiYZmWtN3RYtGZewc4Q3PfJvW3GQ1kCVYaYCAGf5Z2qENzOkeAlu/IghRYgcIvn0KffJxA6vmngwtyW1cuxCLBsFnWozSr/owogOc5+C2Npu6Wdb/lKfBPwF1bTKnwcrNiVMP77OqPXMaV/wRSwDaCWqXnNCN9YTr4ltRXVf2oR0WPuIlGKWQ0tMUBK3NwTrnEQtn07mqXGaxCEByR0aaTO8nquxlKSNTO6c11lqIhmJJoY=" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+01:00" level=debug msg="completed challenge" Mar 04 15:29:38 loe go-librespot[32474]: time="2026-03-04T15:29:38+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 04 15:29:38 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:38 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:38 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:38 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:38 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:39 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:39 loe volumio[31703]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:40 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:40 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:40 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:41 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Mar 04 15:29:41 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:41 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:41 loe go-librespot[32481]: go-librespot daemon starting... Mar 04 15:29:41 loe go-librespot[32482]: time="2026-03-04T15:29:41+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:41 loe go-librespot[32482]: time="2026-03-04T15:29:41+01:00" level=debug msg="app state loaded" Mar 04 15:29:41 loe go-librespot[32482]: time="2026-03-04T15:29:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+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 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+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 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=info msg="zeroconf server listening on port 34739" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=debug msg="obtained new client token: AACZ3uHelp3aeuxVTwxx4Xms8LGBF4d5eUbiMLVJ1DX6COPWoE477EOKPedKo84ISkNFGjxqPfm/okjYgmGD32QzsKNxthHA5wGPkKEkrrjY/0Cdbf6fuwtQJUqjLqzQ7JTm7hLVn9+qhuttfbBlOzLN0QSObV2iqabAQXh41CBjFgo618QspQ5YfQz6xIbv7hY1s6zgGkG17LEW3pHmiP7uxRUQd6VfEJP8VEmed3CnbLZ5AjaR2c8rdw==" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+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 04 15:29:42 loe volumio[31703]: info: Initializing connection to go-librespot Websocket Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+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 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=debug msg="new websocket client" Mar 04 15:29:42 loe volumio[31703]: info: Connection to go-librespot Websocket established Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29:42+01:00" level=debug msg="completed challenge" Mar 04 15:29:42 loe go-librespot[32482]: time="2026-03-04T15:29: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 04 15:29:42 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:42 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:42 loe volumio[31703]: info: Connection to go-librespot Websocket closed Mar 04 15:29:42 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:42 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:42 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:44 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:44 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:44 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:45 loe volumio[31703]: info: Getting Spotify volume Mar 04 15:29:45 loe volumio[31703]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 15:29:45 loe volumio[31703]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 04 15:29:45 loe volumio[31703]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 04 15:29:45 loe volumio[31703]: errno: -111, Mar 04 15:29:45 loe volumio[31703]: code: 'ECONNREFUSED', Mar 04 15:29:45 loe volumio[31703]: syscall: 'connect', Mar 04 15:29:45 loe volumio[31703]: address: '127.0.0.1', Mar 04 15:29:45 loe volumio[31703]: port: 9879, Mar 04 15:29:45 loe volumio[31703]: response: undefined Mar 04 15:29:45 loe volumio[31703]: } Mar 04 15:29:45 loe volumio[31703]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 15:29:45 loe systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Mar 04 15:29:45 loe systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:45 loe systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 04 15:29:45 loe go-librespot[32515]: go-librespot daemon starting... Mar 04 15:29:45 loe go-librespot[32516]: time="2026-03-04T15:29:45+01:00" level=info msg="running go-librespot 0.4.0" Mar 04 15:29:45 loe go-librespot[32516]: time="2026-03-04T15:29:45+01:00" level=debug msg="app state loaded" Mar 04 15:29:45 loe go-librespot[32516]: time="2026-03-04T15:29:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+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 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+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 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+01:00" level=info msg="zeroconf server listening on port 44035" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+01:00" level=debug msg="obtained new client token: AAAQ29s/GseTkj5N0cIFNP8iSeSnZkQQ1g7rSqmp0sRNsgNiBMhFLdf0N8+oiZ/QOFZNHsjvVk4UVVXCsKGM6D/NfVlV7OPweQsLyDzDkzu7AljFYK+R8CHOx1/XIK5krZg9tTicYd0w1SS6Ha7gCkB1rM2irrpmqVQgNDewtvoSpr6hokTtyOndCgtfrKugKHtrlbCs3qfQqnqVkRoUdW6JaGNmQtOeFXq14jI1blNL/7+ku86mWotZng==" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+01:00" level=debug msg="completed keyexchange" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29:46+01:00" level=debug msg="completed challenge" Mar 04 15:29:46 loe go-librespot[32516]: time="2026-03-04T15:29: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 04 15:29:46 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 15:29:46 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 15:29:46 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 04 15:29:46 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 04 15:29:46 loe upmpdcli[1410]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 04 15:29:46 loe sudo[32525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 15:28' Mar 04 15:29:46 loe sudo[32525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"