Mar 26 03:13:00 katarynka sudo[13900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 26 03:13:00 katarynka sudo[13900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:00 katarynka sudo[13902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 26 03:13:00 katarynka sudo[13902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:00 katarynka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Mar 26 03:13:00 katarynka systemd[1]: mpd_monitor.service: Deactivated successfully. Mar 26 03:13:00 katarynka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Mar 26 03:13:00 katarynka systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Mar 26 03:13:00 katarynka sudo[13900]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:00 katarynka mpd_monitor.sh[13905]: MPD Monitor Service: Starting MPD Monitor Service Mar 26 03:13:00 katarynka sudo[13902]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:00 katarynka volumio[13573]: info: Successfully started MPD Monitor Mar 26 03:13:00 katarynka volumio[13573]: info: Successfully started MPD Monitor Mar 26 03:13:01 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 26 03:13:01 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:01 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:01 katarynka go-librespot[13909]: go-librespot daemon starting... Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=debug msg="app state loaded" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:01 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:01 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:01 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=info msg="zeroconf server listening on port 44949" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=debug msg="obtained new client token: AACL3FiddwZbhWBgsNRpvl3w84ESsol3jXqkCK3EBacnj6psJZ+MqLaL0Gy99KRNul/KllKpa7aLQgsHfM3CM12+gMFTw/dSZIiOUdfCwSfKqd+qoLmlZcIgkcA1oy5hHOIfsqm0dRTiqZvP2wAlWcGhS/9usBwcudf4ZNMj6MMZ6z6i8ZP7ghJUgJPxMPg9UmuVwFwNWZS0x5sN0MlNTzC/72EWu5ndtvuelMXUpf23l5ZdfF+3aQKGWCU=" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 26 03:13:01 katarynka go-librespot[13910]: time="2026-03-26T03:13:01+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 26 03:13:02 katarynka go-librespot[13910]: time="2026-03-26T03:13:02+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:02 katarynka go-librespot[13910]: time="2026-03-26T03:13:02+01:00" level=debug msg="completed challenge" Mar 26 03:13:02 katarynka go-librespot[13910]: time="2026-03-26T03:13:02+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:02 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:02 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:02 katarynka volumio[13573]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 26 03:13:02 katarynka volumio[13573]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:02 katarynka volumio[13573]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:13:03 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:03 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:03 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:05 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 26 03:13:05 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:05 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:05 katarynka go-librespot[13918]: go-librespot daemon starting... Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="app state loaded" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:05 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:05 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:05 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13: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-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=info msg="zeroconf server listening on port 37875" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="obtained new client token: AAABZrCqeCjRF32iO57zKGFFXYSd+xvgF94KWxbRaCj347GX6ddQtIZliYT/kOtcI3im/zjDLV3bx4e+AQTrKU2M+TYmdgWzfzJ2p1aifydR7gugG1Vx6J+Vqx3fiMxDKj2RyK7TBsSGkhDFlGP01giCSj8svEq+l/bzM9vPkiOdkBzhbKagJxQB+APazZtGo7hMjPq5EfRo3ITZgJLkecWZVNZ2MLoHMpOLfbZRWqMynIjZr9Mpwbm6Vsw=" Mar 26 03:13:05 katarynka volumio[13573]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="new websocket client" Mar 26 03:13:05 katarynka volumio[13573]: info: Connection to go-librespot Websocket established Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:05 katarynka go-librespot[13919]: time="2026-03-26T03:13:05+01:00" level=debug msg="completed challenge" Mar 26 03:13:06 katarynka go-librespot[13919]: time="2026-03-26T03:13:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:06 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:06 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:06 katarynka volumio[13573]: info: Connection to go-librespot Websocket closed Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin bluetooth to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin multiroom to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin metavolumio to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin cd_controller to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 26 03:13:06 katarynka volumio[13573]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 26 03:13:07 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:07 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:07 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:08 katarynka volumio[13573]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 26 03:13:08 katarynka volumio[13573]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 26 03:13:08 katarynka volumio[13573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:08 katarynka volumio[13573]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:08 katarynka volumio[13573]: info: Starting MyVolumio Remote Streaming Endpoints Mar 26 03:13:08 katarynka volumio[13573]: info: MyVolumio login type: Token Mar 26 03:13:08 katarynka volumio[13573]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 26 03:13:08 katarynka volumio[13573]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 26 03:13:09 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 26 03:13:09 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:09 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:09 katarynka go-librespot[13926]: go-librespot daemon starting... Mar 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13:09+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13:09+01:00" level=debug msg="app state loaded" Mar 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13:09+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:09 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:09 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:09 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13: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 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13: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 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13: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 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13:09+01:00" level=info msg="zeroconf server listening on port 42303" Mar 26 03:13:09 katarynka go-librespot[13927]: time="2026-03-26T03:13:09+01:00" level=debug msg="obtained new client token: AABXS9uYrb8USGF4PNz0vy+/kDh5ZK8uLgntAHaDhnLVucc6w9RJ3kAGGfzMMll1xo/5qs9q4FHpHPzJDE8eq4ym+eprEXAE8dD55bkthTdBsofAW6B4LpwiawqXm4wczsjU7HnfSPKVYhWiAA8I/33MlDUqVIyGS3JnoaA/FrVhTkqjCsbY0jkUWHJrG2TMh0Bs36J4PmIsZSzwSRbQox1G/78cTj+Ga/QFun3IvB9bznHH6aJW6+i1Nzw=" Mar 26 03:13:09 katarynka volumio[13573]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 26 03:13:09 katarynka volumio[13573]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 26 03:13:10 katarynka volumio[13573]: info: Streaming services startup Mar 26 03:13:10 katarynka go-librespot[13927]: time="2026-03-26T03:13:10+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:10 katarynka volumio[13573]: info: Starting Streaming Daemon Mar 26 03:13:10 katarynka sudo[13935]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 26 03:13:10 katarynka sudo[13935]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:10 katarynka volumio[13573]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 26 03:13:10 katarynka go-librespot[13927]: time="2026-03-26T03:13:10+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:10 katarynka go-librespot[13927]: time="2026-03-26T03:13:10+01:00" level=debug msg="completed challenge" Mar 26 03:13:10 katarynka go-librespot[13927]: time="2026-03-26T03:13:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:10 katarynka sudo[13935]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:10 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:10 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:10 katarynka volumio[13573]: info: Getting Spotify volume Mar 26 03:13:10 katarynka volumio[13573]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:10 katarynka volumio[13573]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:13:10 katarynka volumio[13573]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:13:10 katarynka volumio[13573]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 26 03:13:10 katarynka volumio[13573]: errno: -111, Mar 26 03:13:10 katarynka volumio[13573]: code: 'ECONNREFUSED', Mar 26 03:13:10 katarynka volumio[13573]: syscall: 'connect', Mar 26 03:13:10 katarynka volumio[13573]: address: '127.0.0.1', Mar 26 03:13:10 katarynka volumio[13573]: port: 9879, Mar 26 03:13:10 katarynka volumio[13573]: response: undefined Mar 26 03:13:10 katarynka volumio[13573]: } Mar 26 03:13:10 katarynka volumio[13573]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:13:11 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:11 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:11 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:11 katarynka sudo[13969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 03:12' Mar 26 03:13:11 katarynka sudo[13969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:12 katarynka sudo[13969]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:12 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 26 03:13:12 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 26 03:13:12 katarynka systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:12 katarynka systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 26 03:13:12 katarynka systemd[1]: volumio.service: Consumed 48.728s CPU time. Mar 26 03:13:12 katarynka systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 26 03:13:12 katarynka systemd[1]: dynamicswap.service: Deactivated successfully. Mar 26 03:13:12 katarynka systemd[1]: volumio.service: Scheduled restart job, restart counter is at 672. Mar 26 03:13:12 katarynka systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 26 03:13:12 katarynka systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 26 03:13:12 katarynka systemd[1]: volumio.service: Consumed 48.728s CPU time. Mar 26 03:13:12 katarynka systemd[1]: Started volumio.service - Volumio Backend Module. Mar 26 03:13:12 katarynka systemd[1]: dynamicswap.service: Deactivated successfully. Mar 26 03:13:13 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 26 03:13:13 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:13 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:13 katarynka go-librespot[13997]: go-librespot daemon starting... Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=debug msg="app state loaded" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:13 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:13 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:13 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=info msg="zeroconf server listening on port 33949" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=debug msg="obtained new client token: AAADqYVI1UtZ0WK0mGN5rjwlLmk3zABW6tJigJTPb8v49xbZDSY0L02sNRwxHqyPMywVfhTcSVVRqjbhBXJZyB4H4IpBYukYypiHcTGUCrgm0hYxW9iW3hFgudrERsHE0fbSM8A2MZAw5jc5hHgSmGJ6I76JEcuggZaCRTDCGHjzdNJnHM0Hib0IWFrB4oWJqVMBNwrPt8Y8mkKajnTtAvCxUi/xdDQhXeqWUYY/X68jXg63IaL1hNu1WyU=" Mar 26 03:13:13 katarynka go-librespot[13998]: time="2026-03-26T03:13:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:14 katarynka go-librespot[13998]: time="2026-03-26T03:13:14+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:14 katarynka go-librespot[13998]: time="2026-03-26T03:13:14+01:00" level=debug msg="completed challenge" Mar 26 03:13:14 katarynka go-librespot[13998]: time="2026-03-26T03:13:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:14 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:14 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:15 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:15 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:15 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:16 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:16 katarynka volumio[13982]: info: ----- Volumio3 ---- Mar 26 03:13:16 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:16 katarynka volumio[13982]: info: ----- System startup ---- Mar 26 03:13:16 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:17 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Mar 26 03:13:17 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:17 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:17] [connect] Successful connection Mar 26 03:13:17 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:17 katarynka go-librespot[14012]: go-librespot daemon starting... Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=debug msg="app state loaded" Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:17 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:17 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:17 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=info msg="zeroconf server listening on port 46401" Mar 26 03:13:17 katarynka volumio[13982]: info: MYVOLUMIO Environment detected Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=debug msg="obtained new client token: AACZrxKhbv9AJhH64EnEWfPL7UyDslpZNge6SYFNJhAFQMDlZRs84Y0ZpxFzzN7jOCgxjW1Zgl1ZrhWLruYWShqi27ee8l1IMdtDBhTahQ9wajzmqsH56ncNpEBKTENWyPEo/04nRHlngLrMakjAJeJcgr5uTpQqpWyE/PVvMqSd4gdzOO1+ugd7xZrM2/lwoTDnYNO1KwYwulUZze0WHNPfnkinknHtkt/j7+XQ2yg4i5x/4wfzfqtusQo=" Mar 26 03:13:17 katarynka go-librespot[14013]: time="2026-03-26T03:13:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:17 katarynka volumio[13982]: info: Plugin folders cleanup Mar 26 03:13:17 katarynka volumio[13982]: info: Scanning into folder /volumio/app/plugins/ Mar 26 03:13:17 katarynka volumio[13982]: info: Scanning category audio_interface Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning category miscellanea Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning category music_service Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning category plugins.json Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning category system_controller Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning category user_interface Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning into folder /data/plugins/ Mar 26 03:13:18 katarynka volumio[13982]: info: Scanning category music_service Mar 26 03:13:18 katarynka volumio[13982]: info: Plugin folders cleanup completed Mar 26 03:13:18 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:18 katarynka volumio[13982]: info: ----- Core plugins startup ---- Mar 26 03:13:18 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:18 katarynka volumio[13982]: info: Loading plugins from folder /volumio/app/plugins/ Mar 26 03:13:18 katarynka volumio[13982]: info: Adding plugin upnp to MyMusic Plugins Mar 26 03:13:18 katarynka go-librespot[14013]: time="2026-03-26T03:13:18+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:18 katarynka volumio[13982]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 26 03:13:18 katarynka go-librespot[14013]: time="2026-03-26T03:13:18+01:00" level=debug msg="completed challenge" Mar 26 03:13:18 katarynka volumio[13982]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 26 03:13:18 katarynka volumio[13982]: info: Loading plugins from folder /data/plugins/ Mar 26 03:13:18 katarynka volumio[13982]: info: Loading plugin "system"... Mar 26 03:13:18 katarynka go-librespot[14013]: time="2026-03-26T03:13:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:18 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:18 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:18 katarynka volumio[13982]: info: Loading plugin "appearance"... Mar 26 03:13:19 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:19 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:19 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "network"... Mar 26 03:13:20 katarynka volumio[13982]: info: Refreshing Cached IP Addresses Mar 26 03:13:20 katarynka sudo[14027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 03:13:20 katarynka sudo[14027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:20 katarynka sudo[14029]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 03:13:20 katarynka sudo[14029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "services"... Mar 26 03:13:20 katarynka sudo[14029]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:20 katarynka sudo[14027]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "alsa_controller"... Mar 26 03:13:20 katarynka sudo[14037]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 26 03:13:20 katarynka sudo[14037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:20 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "wizard"... Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "networkfs"... Mar 26 03:13:20 katarynka volumio[13982]: info: Starting Udev Watcher for removable devices Mar 26 03:13:20 katarynka volumio[13982]: info: Ignoring mount for partition: boot Mar 26 03:13:20 katarynka volumio[13982]: info: Ignoring mount for partition: volumio Mar 26 03:13:20 katarynka volumio[13982]: info: Ignoring mount for partition: volumio_data Mar 26 03:13:20 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "volumio_command_line_client"... Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "upnp"... Mar 26 03:13:20 katarynka volumio[13982]: info: [1774491200636] Starting Upmpd Daemon Mar 26 03:13:20 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "my_music"... Mar 26 03:13:20 katarynka volumio[13982]: info: Loading plugin "mpd"... Mar 26 03:13:21 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Mar 26 03:13:21 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:21 katarynka sudo[14037]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:21 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:21 katarynka go-librespot[14063]: go-librespot daemon starting... Mar 26 03:13:21 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:21 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:21 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=debug msg="app state loaded" Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:21 katarynka volumio[13982]: info: Loading plugin "upnp_browser"... Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13: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-gew1.spotify.com:80]" Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=info msg="zeroconf server listening on port 37503" Mar 26 03:13:21 katarynka go-librespot[14064]: time="2026-03-26T03:13:21+01:00" level=debug msg="obtained new client token: AABsiL/UPEaYCvQmZFQ8GEGd1c2+jZd1aADFhSZPBHt3/30qEdz+wyTb4acUMCRzfUy9X3PaNkFXT8JnmEoWV/GiM6rpnaRX4UpRu1NIMKlY1KIGOOUDWmZGfMUiHVwngmGm0XsVPkCD5csbav5eTCvzJiat/GV6rmaOysmRiOMkvVsUk2dQoq/1Ff+8/sXd7ILRE14hhibxGCzoVdbdGiCTURLDHnE8y4maoxJa0o+CHzqTZtMGg6Io+as=" Mar 26 03:13:22 katarynka go-librespot[14064]: time="2026-03-26T03:13:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:22 katarynka go-librespot[14064]: time="2026-03-26T03:13:22+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:22 katarynka go-librespot[14064]: time="2026-03-26T03:13:22+01:00" level=debug msg="completed challenge" Mar 26 03:13:22 katarynka go-librespot[14064]: time="2026-03-26T03:13:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:22 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:22 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:23 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:23 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:23 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:24 katarynka volumio[13982]: info: Starting UPNP Browser Mar 26 03:13:24 katarynka volumio[13982]: info: Loading plugin "alarm-clock"... Mar 26 03:13:24 katarynka volumio[13982]: info: Loading plugin "airplay_emulation"... Mar 26 03:13:24 katarynka volumio[13982]: info: Starting Shairport Sync Mar 26 03:13:24 katarynka volumio[13982]: info: Loading plugin "last_100"... Mar 26 03:13:24 katarynka volumio[13982]: info: Loading plugin "webradio"... Mar 26 03:13:24 katarynka volumio[13982]: info: Loading plugin "i2s_dacs"... Mar 26 03:13:24 katarynka volumio[13982]: info: Loading plugin "volumiodiscovery"... Mar 26 03:13:25 katarynka volumio[13982]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 03:13:25 katarynka volumio[13982]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:13:25 katarynka volumio[13982]: *** WARNING *** For more information see Mar 26 03:13:25 katarynka volumio[13982]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 03:13:25 katarynka volumio[13982]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:13:25 katarynka volumio[13982]: *** WARNING *** For more information see Mar 26 03:13:25 katarynka node[13982]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 03:13:25 katarynka node[13982]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:13:25 katarynka node[13982]: *** WARNING *** For more information see Mar 26 03:13:25 katarynka node[13982]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 03:13:25 katarynka node[13982]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:13:25 katarynka node[13982]: *** WARNING *** For more information see Mar 26 03:13:25 katarynka volumio[13982]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 26 03:13:25 katarynka volumio[13982]: info: Discovery: Started advertising with name: katarynka Mar 26 03:13:25 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 03:13:25 katarynka volumio[13982]: info: Loading plugin "spop"... Mar 26 03:13:25 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Mar 26 03:13:25 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:25 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:25 katarynka go-librespot[14074]: go-librespot daemon starting... Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=debug msg="app state loaded" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:25 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:25 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:25 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13: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-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=info msg="zeroconf server listening on port 34083" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=debug msg="obtained new client token: AAA7wZtGVArxn0aqRCDBzF2kJLZWAytZW5q2vKy4vjlb7+LbYSA4qvXCjjYAqb54Q4CazOtL9iV7Z4MTKtNN7pALpH/EzMkVRPgZHCdxrWWIYQO/g5WIvOQW9eXqAtzBzd2gtxYN3jyij8nzlvl47qkeo1TqvTC/5BoDtzrfjXAuLdfrPr7lPgZZRN+MPy263VdNoPNnpukKAyo8jXlCT5U663tietnTRxPLvlY3vP/yrE1CldmL2o+UU3I=" Mar 26 03:13:25 katarynka go-librespot[14075]: time="2026-03-26T03:13:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:26 katarynka go-librespot[14075]: time="2026-03-26T03:13:26+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:26 katarynka go-librespot[14075]: time="2026-03-26T03:13:26+01:00" level=debug msg="completed challenge" Mar 26 03:13:26 katarynka go-librespot[14075]: time="2026-03-26T03:13:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:26 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:26 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:27 katarynka volumio[13982]: info: Loading plugin "outputs"... Mar 26 03:13:27 katarynka volumio[13982]: info: Loading plugin "albumart"... Mar 26 03:13:27 katarynka volumio[13982]: info: Plugin example_plugin is not enabled Mar 26 03:13:27 katarynka volumio[13982]: info: Loading plugin "inputs"... Mar 26 03:13:27 katarynka volumio[13982]: info: Loading plugin "updater_comm"... Mar 26 03:13:27 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:27 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:27 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:27 katarynka volumio[13982]: info: Plugin mpdemulation is not enabled Mar 26 03:13:27 katarynka volumio[13982]: info: Loading plugin "rest_api"... Mar 26 03:13:27 katarynka volumio[13982]: info: Loading plugin "websocket"... Mar 26 03:13:27 katarynka volumio[13982]: info: Starting Socket.io Server version 1.7.4 Mar 26 03:13:27 katarynka volumio[13982]: info: Loading i18n strings for locale pl Mar 26 03:13:27 katarynka volumio[13982]: Updating browse sources language Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::initPlayerControls Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 03:13:27 katarynka volumio[13982]: Express server listening on port 3000 Mar 26 03:13:27 katarynka volumio[13982]: [Metrics] WebUI: 12s 611.74ms Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::resetVolumioState Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::getcurrentVolume Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 03:13:28 katarynka volumio[13982]: info: Cannot read play queue from file Mar 26 03:13:28 katarynka volumio[13982]: info: Volumio Network Manager: Network status updated: 2 Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:28 katarynka volumio[14082]: Forking 3 albumart workers Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::setRepeat null single undefined Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::pushState Mar 26 03:13:28 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::volumioPushState Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::setRandom null Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::pushState Mar 26 03:13:28 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::volumioPushState Mar 26 03:13:28 katarynka volumio[13982]: info: Setting Device type: Raspberry PI Mar 26 03:13:28 katarynka volumio[13982]: info: Completed loading Core Plugins Mar 26 03:13:28 katarynka volumio[13982]: info: Preparing to generate the ALSA configuration file Mar 26 03:13:28 katarynka volumio[13982]: info: VolumeController:: Volume=96 Mute =false Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::pushState Mar 26 03:13:28 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::volumioPushState Mar 26 03:13:28 katarynka volumio[13982]: info: CoreStateMachine::updateTrackBlock Mar 26 03:13:28 katarynka volumio[13982]: info: CorePlayQueue::getTrackBlock Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 03:13:28 katarynka volumio[13982]: info: Asound.conf file unchanged, so no further update is needed Mar 26 03:13:28 katarynka volumio[13982]: info: Output device has changed, restarting MPD Mar 26 03:13:28 katarynka volumio[13982]: info: Output device has changed, restarting Shairport Sync Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:28 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:28 katarynka sudo[14137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 03:13:28 katarynka sudo[14137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:28 katarynka sudo[14135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 03:13:28 katarynka sudo[14135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:28 katarynka sudo[14135]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:28 katarynka volumio[13982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 03:13:28 katarynka volumio[13982]: info: ___________ START PLUGINS ___________ Mar 26 03:13:28 katarynka volumio[13982]: info: ControllerMpd::onStart: Initializing MPD Mar 26 03:13:28 katarynka volumio[13982]: info: Creating MPD Configuration file Mar 26 03:13:28 katarynka systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 03:13:29 katarynka sudo[14146]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 03:13:29 katarynka sudo[14146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:29 katarynka sudo[14146]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 03:13:29 katarynka volumio[13982]: info: [1774491209077] CoreMusicLibrary::Adding element Serwery Mediów Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:13:29 katarynka systemd[1]: mpd.service: Deactivated successfully. Mar 26 03:13:29 katarynka systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 03:13:29 katarynka systemd[1]: mpd.service: Consumed 7.793s CPU time. Mar 26 03:13:29 katarynka systemd[1]: mpd.socket: Deactivated successfully. Mar 26 03:13:29 katarynka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 03:13:29 katarynka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 03:13:29 katarynka sudo[14149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 03:13:29 katarynka sudo[14149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:29 katarynka volumio[13982]: info: UPNP Browser: Client initialized successfully Mar 26 03:13:29 katarynka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 03:13:29 katarynka systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:29 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Mar 26 03:13:29 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:29 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:29 katarynka volumio[13982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:29 katarynka go-librespot[14157]: go-librespot daemon starting... Mar 26 03:13:29 katarynka systemd[1]: mpd.service: Deactivated successfully. Mar 26 03:13:29 katarynka systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 03:13:29 katarynka systemd[1]: mpd.socket: Deactivated successfully. Mar 26 03:13:29 katarynka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 03:13:29 katarynka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 03:13:29 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:29 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:29 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:29 katarynka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 03:13:29 katarynka systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 03:13:29 katarynka go-librespot[14159]: time="2026-03-26T03:13:29+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:29 katarynka go-librespot[14159]: time="2026-03-26T03:13:29+01:00" level=debug msg="app state loaded" Mar 26 03:13:29 katarynka go-librespot[14159]: time="2026-03-26T03:13:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:29 katarynka volumio[13982]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 03:13:29 katarynka volumio[13982]: info: [1774491209485] CoreMusicLibrary::Adding element Last_100 Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 03:13:29 katarynka volumio[13982]: info: [1774491209528] CoreMusicLibrary::Adding element Webradio Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 03:13:29 katarynka volumio[13982]: info: Initializing BBC Radios Mar 26 03:13:29 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:29] [connect] Successful connection Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 03:13:29 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:29 katarynka sudo[14170]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 03:13:29 katarynka sudo[14170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 03:13:30 katarynka volumio[13982]: info: Creating Spotify config file Mar 26 03:13:30 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:30 katarynka sudo[14170]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=info msg="zeroconf server listening on port 43243" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="obtained new client token: AAC6aVOUAnSaj4a1iULoui3fJXjfd5PnCtzHqKqEfjE+JfvDgT6FXRyMZ2rhrakTGXM3baAKi43OE3+mAvxr8XhHqef8VrF+vkOlutKolle133Jeq33p6SGqkDxtuCO8f/M1jg8U/a3kC8eHBtmrMk1MIVsFpIrdPQ9od/G4VjarhNuyIyoa7vWFUCM/JPwvTJoKuih/N+GVqq4l7PgFOAfUcXwhxC1vb/LdMpR0MqEYCTu/OEG80NdM" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=debug msg="completed challenge" Mar 26 03:13:30 katarynka go-librespot[14159]: time="2026-03-26T03:13:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:30 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:30 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:31 katarynka volumio[13982]: info: Volumio Calling Home Mar 26 03:13:31 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:31 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:31 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:32 katarynka volumio[14104]: Starting albumart workers Mar 26 03:13:32 katarynka volumio[13982]: info: Discovery: adding 4d616d77-6a8c-4da2-85a4-840d70f98e51 Mar 26 03:13:32 katarynka volumio[13982]: info: Discovery: Found device katarynka Mar 26 03:13:32 katarynka volumio[13982]: info: CoreCommandRouter::volumioGetState Mar 26 03:13:32 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:32 katarynka volumio[13982]: info: Discovery: this is already registered, 4d616d77-6a8c-4da2-85a4-840d70f98e51 Mar 26 03:13:32 katarynka sudo[14204]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 03:13:32 katarynka sudo[14204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:32 katarynka volumio[13982]: info: Discovery: Found device katarynka Mar 26 03:13:32 katarynka volumio[13982]: info: CoreCommandRouter::volumioGetState Mar 26 03:13:32 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:32 katarynka sudo[14204]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:32 katarynka sudo[14206]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 03:13:32 katarynka sudo[14206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:32 katarynka sudo[14206]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:32 katarynka volumio[13982]: info: MPD Permissions set Mar 26 03:13:32 katarynka volumio[13982]: info: MPD Permissions set Mar 26 03:13:32 katarynka volumio[13982]: info: VolumeController:: Volume=96 Mute =false Mar 26 03:13:32 katarynka volumio[13982]: info: CoreStateMachine::pushState Mar 26 03:13:32 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:32 katarynka volumio[13982]: info: CoreCommandRouter::volumioPushState Mar 26 03:13:32 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774491209 101 Mar 26 03:13:32 katarynka volumio[13982]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Mar 26 03:13:32 katarynka volumio[13982]: info: Volumio called home Mar 26 03:13:32 katarynka volumio[13982]: info: Spotify config file written Mar 26 03:13:32 katarynka volumio[14102]: Starting albumart workers Mar 26 03:13:32 katarynka volumio[14101]: Starting albumart workers Mar 26 03:13:32 katarynka sudo[14210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 26 03:13:32 katarynka sudo[14210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:32 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:33 katarynka volumio[13982]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka go-librespot[14212]: go-librespot daemon starting... Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka sudo[14210]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=debug msg="app state loaded" Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:33 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:13:33 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:33 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:33 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:33 katarynka volumio[13982]: info: No need to fix Spotify hosts Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=info msg="zeroconf server listening on port 44533" Mar 26 03:13:33 katarynka go-librespot[14213]: time="2026-03-26T03:13:33+01:00" level=debug msg="obtained new client token: AADpgSGP3RpnHDjBXBcCSCxb8bCZdPFpbgJXgR5nzlag3akRjzvLmQmDG3KLE06OIhrCptDO0CwOTamGdQz4iYXqSgc7aa4Hsxuv6+NkA+pdK66zZQF9sNVqIZ6+inrvuuw6oj9Xjj3d0eEtIKf8fcmNdsX+X4Xi63ghTsLZuBPq1B3yWJx0un6ROqIvc1bWhdODYZhtQJpUWb+/Xnrhc0U3ffn+SPqc6Jr3jrIAXFskbVdckiGwkMdSLTQ=" Mar 26 03:13:34 katarynka go-librespot[14213]: time="2026-03-26T03:13:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:34 katarynka go-librespot[14213]: time="2026-03-26T03:13:34+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:34 katarynka go-librespot[14213]: time="2026-03-26T03:13:34+01:00" level=debug msg="completed challenge" Mar 26 03:13:34 katarynka go-librespot[14213]: time="2026-03-26T03:13:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:34 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:34 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:34 katarynka volumio[13982]: info: Starting Shairport Sync Mar 26 03:13:34 katarynka volumio[13982]: info: Starting Shairport Sync Mar 26 03:13:34 katarynka volumio[13982]: info: Starting Shairport Sync Mar 26 03:13:34 katarynka sudo[14232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 03:13:34 katarynka sudo[14232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:34 katarynka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 03:13:34 katarynka sudo[14239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 03:13:34 katarynka sudo[14235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 03:13:34 katarynka sudo[14235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:34 katarynka sudo[14239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:34 katarynka systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 03:13:34 katarynka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 03:13:34 katarynka systemd[1]: shairport-sync.service: Consumed 2.354s CPU time. Mar 26 03:13:34 katarynka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 03:13:34 katarynka sudo[14247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 26 03:13:34 katarynka sudo[14232]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:34 katarynka sudo[14247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:34 katarynka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 03:13:34 katarynka systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 03:13:34 katarynka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 03:13:34 katarynka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 03:13:34 katarynka sudo[14235]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:34 katarynka sudo[14239]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:35 katarynka volumio[13982]: info: Shairport-Sync Started Mar 26 03:13:35 katarynka sudo[14247]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:35 katarynka volumio[13982]: Error adding Membership: Error: addMembership EINVAL Mar 26 03:13:35 katarynka volumio[13982]: info: Shairport-Sync Started Mar 26 03:13:35 katarynka volumio[13982]: info: Shairport-Sync Started Mar 26 03:13:35 katarynka volumio[13982]: info: An error occurred while refreshing Spotify Token Error: Bad Request Mar 26 03:13:35 katarynka volumio[13982]: info: Upmpdcli Daemon Started Mar 26 03:13:35 katarynka volumio[13982]: info: CoreCommandRouter::volumioGetState Mar 26 03:13:35 katarynka volumio[13982]: info: CorePlayQueue::getTrack 0 Mar 26 03:13:35 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:35 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:35 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:37 katarynka volumio[13982]: info: go-librespot daemon successfully initialized Mar 26 03:13:37 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:37 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:37 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:37 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Mar 26 03:13:37 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:37 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:37 katarynka go-librespot[14290]: go-librespot daemon starting... Mar 26 03:13:37 katarynka go-librespot[14291]: time="2026-03-26T03:13:37+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:37 katarynka go-librespot[14291]: time="2026-03-26T03:13:37+01:00" level=debug msg="app state loaded" Mar 26 03:13:37 katarynka go-librespot[14291]: time="2026-03-26T03:13:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:37 katarynka mpd[14185]: 2026-03-26T03:13:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 26 03:13:37 katarynka systemd[1]: Started mpd.service - Music Player Daemon. Mar 26 03:13:37 katarynka sudo[14137]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:37 katarynka sudo[14149]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:37 katarynka volumio[13982]: info: Completed starting Core Plugins Mar 26 03:13:37 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:37 katarynka volumio[13982]: info: ----- MyVolumio plugins startup ---- Mar 26 03:13:37 katarynka volumio[13982]: info: ------------------------------------------- Mar 26 03:13:37 katarynka volumio[13982]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:38 katarynka volumio[13982]: error: MPD error: The expression evaluated to a falsy value: Mar 26 03:13:38 katarynka volumio[13982]: assert.ok(self.idling) Mar 26 03:13:38 katarynka volumio[13982]: error: The expression evaluated to a falsy value: Mar 26 03:13:38 katarynka volumio[13982]: assert.ok(self.idling) Mar 26 03:13:38 katarynka volumio[13982]: error: updateQueue error: null Mar 26 03:13:38 katarynka volumio[13982]: info: MPD running with PID14185 Mar 26 03:13:38 katarynka volumio[13982]: ,establishing connection Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=info msg="zeroconf server listening on port 37847" Mar 26 03:13:38 katarynka volumio[13982]: error: updateQueue error: null Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="obtained new client token: AAAKRmW1VPuVD/du/7U3qika2+zF6PW1qvDkEQVTT9PAjIy9MsUCplKrcOKwqgtQpZeEpuR7yD/t52aA5sqb+JCQZy9BBVboR6pbQs14nnuPAKyCwUIWyGAysN8d3wJ2WY6edLuAjj4Bfd6IRfrxU0eaYMAb5x7s2rQuGWJncMBDUiRgCGINwGUY70EfgeioRfHXt/UYxXTmy67c4XxYyY7mMevk0stBKRFzvSBn+46k6fdqwFupwsBp" Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:38 katarynka go-librespot[14291]: time="2026-03-26T03:13:38+01:00" level=debug msg="completed challenge" Mar 26 03:13:39 katarynka go-librespot[14291]: time="2026-03-26T03:13:39+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:39 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:39 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:39 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:39 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:39 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:40 katarynka volumio[13982]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:40 katarynka volumio[13982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:13:40 katarynka sudo[14317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 26 03:13:40 katarynka sudo[14317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:40 katarynka sudo[14319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 26 03:13:40 katarynka sudo[14319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:40 katarynka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Mar 26 03:13:40 katarynka systemd[1]: mpd_monitor.service: Deactivated successfully. Mar 26 03:13:40 katarynka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Mar 26 03:13:41 katarynka systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Mar 26 03:13:41 katarynka sudo[14317]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:41 katarynka mpd_monitor.sh[14323]: MPD Monitor Service: Starting MPD Monitor Service Mar 26 03:13:41 katarynka volumio[13982]: info: Successfully started MPD Monitor Mar 26 03:13:41 katarynka sudo[14319]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:41 katarynka volumio[13982]: info: Successfully started MPD Monitor Mar 26 03:13:41 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:41 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:41 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:42 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Mar 26 03:13:42 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:42 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:42 katarynka go-librespot[14327]: go-librespot daemon starting... Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=debug msg="app state loaded" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=info msg="zeroconf server listening on port 37107" Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=debug msg="obtained new client token: AADiNBMJwOjOO2W5KXqvF6G5nSKv75lJ21/hMr6S5ta14wtVQtPSSPcToX/Mldldf8yCMjtKbXuN7Bz/+4fs/Cfv6/G0GMMbHrSXBekbh4GA6MA04uuHMtXleaFJlD8deoXKgKH098nSkGYBNEF6DjWGQ/sOglYMoVjQgee94Yn5MvQVE73VQRVFI9l/nnWDfmIs3h5xdpLPDbpWCB9Jp1xlKo0BGRzpJea27aqB/ipUW/hmKAkhoMoQXOs=" Mar 26 03:13:42 katarynka volumio[13982]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13: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 26 03:13:42 katarynka go-librespot[14328]: time="2026-03-26T03:13:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 26 03:13:43 katarynka go-librespot[14328]: time="2026-03-26T03:13:43+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:43 katarynka go-librespot[14328]: time="2026-03-26T03:13:43+01:00" level=debug msg="completed challenge" Mar 26 03:13:43 katarynka go-librespot[14328]: time="2026-03-26T03:13:43+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:43 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:43 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:43 katarynka volumio[13982]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:43 katarynka volumio[13982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:13:43 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:43 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:43 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:45 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:45 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:45 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:46 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Mar 26 03:13:46 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:46 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:46 katarynka go-librespot[14336]: go-librespot daemon starting... Mar 26 03:13:46 katarynka volumio[13982]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:46 katarynka volumio[13982]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=debug msg="app state loaded" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin bluetooth to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin multiroom to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin metavolumio to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin cd_controller to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 26 03:13:46 katarynka volumio[13982]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13: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-gew1.spotify.com:80]" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=info msg="zeroconf server listening on port 42911" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=debug msg="obtained new client token: AABFa+qCfsfJNixiZ6FiwZTBpMhOfEQOdA8+oEYFuc0ZMExBKxeBGabbrtg25MGcs4DPc6+2vUhXotvF1qt05NK9Qqr35vzcD5XiyUUIb+XSXZ+gGZWIEDc46A0MYLeHgszy1SH2S1P4YzlYiSeo8DUHQuBTuxBnHS7x/fL0eb3mtOQcNM/BJcJLHCWru5OWSw8iKtuF0Gn41+owQztGcN9t9oujiFwrCLj35kV6vXIs1ojRvAHm7537alI=" Mar 26 03:13:46 katarynka go-librespot[14337]: time="2026-03-26T03:13:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:47 katarynka go-librespot[14337]: time="2026-03-26T03:13:47+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:47 katarynka go-librespot[14337]: time="2026-03-26T03:13:47+01:00" level=debug msg="completed challenge" Mar 26 03:13:47 katarynka go-librespot[14337]: time="2026-03-26T03:13:47+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:47 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:47 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:47 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:47 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:47 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:48 katarynka volumio[13982]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 26 03:13:48 katarynka volumio[13982]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 26 03:13:48 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:48 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:48 katarynka volumio[13982]: info: Starting MyVolumio Remote Streaming Endpoints Mar 26 03:13:48 katarynka volumio[13982]: info: MyVolumio login type: Token Mar 26 03:13:48 katarynka volumio[13982]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 26 03:13:48 katarynka volumio[13982]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 26 03:13:49 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:49 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:49 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:50 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Mar 26 03:13:50 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:50 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:50 katarynka go-librespot[14345]: go-librespot daemon starting... Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=debug msg="app state loaded" Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:50 katarynka volumio[13982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 26 03:13:50 katarynka volumio[13982]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 26 03:13:50 katarynka volumio[13982]: info: Streaming services startup Mar 26 03:13:50 katarynka volumio[13982]: info: Starting Streaming Daemon Mar 26 03:13:50 katarynka sudo[14354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 26 03:13:50 katarynka volumio[13982]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 26 03:13:50 katarynka sudo[14354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:50 katarynka sudo[14354]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:50 katarynka volumio[13982]: info: Initializing connection to go-librespot Websocket Mar 26 03:13:50 katarynka volumio[13982]: error: Cannot start Volumio Streaming Daemon Mar 26 03:13:50 katarynka volumio[13982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 26 03:13:50 katarynka volumio[13982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=debug msg="new websocket client" Mar 26 03:13:50 katarynka volumio[13982]: info: Connection to go-librespot Websocket established Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=info msg="zeroconf server listening on port 45735" Mar 26 03:13:50 katarynka go-librespot[14346]: time="2026-03-26T03:13:50+01:00" level=debug msg="obtained new client token: AABoKDB/cKemOe+haMsIWxrlu0cM1vubFBXkYEeFrR9P3VhmFBBIIA4OhIlL+RHWsnDnlOT0NBmShVjXtlnFjgJ7bLyLTGPy+6yp5PYZMz5mKKBecowRxUymdwQw0NRDPKrLCxBYoGRifyZIbhYG1VaVTOcmLW+RzehWg/PxJRNEvbR1wkLHABmZSgu7UHeKr7VMajIJ7qpfAki+SNMZ9I6sRrvYZyS8Q3xkxr0cN0CLHfXVblvifOa6P1I=" Mar 26 03:13:51 katarynka go-librespot[14346]: time="2026-03-26T03:13:51+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:51 katarynka go-librespot[14346]: time="2026-03-26T03:13:51+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:51 katarynka go-librespot[14346]: time="2026-03-26T03:13:51+01:00" level=debug msg="completed challenge" Mar 26 03:13:51 katarynka go-librespot[14346]: time="2026-03-26T03:13:51+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:51 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:51 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:51 katarynka volumio[13982]: info: Connection to go-librespot Websocket closed Mar 26 03:13:51 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:51 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:51 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:51 katarynka volumio[13982]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 26 03:13:52 katarynka volumio[13982]: info: MyVolumio token set successfully Mar 26 03:13:52 katarynka volumio[13982]: info: MYVOLUMIO: Adding device Mar 26 03:13:52 katarynka volumio[13982]: info: MYVOLUMIO: Evaluating Server Mar 26 03:13:52 katarynka volumio[13982]: info: MyVolumio status changed Mar 26 03:13:52 katarynka volumio[13982]: info: Streaming services startup Mar 26 03:13:52 katarynka volumio[13982]: info: Starting Streaming Daemon Mar 26 03:13:52 katarynka volumio[13982]: info: Removing browser output: myVolumio user plan is not superstar Mar 26 03:13:52 katarynka volumio[13982]: info: Removing audio output: Mar 26 03:13:52 katarynka volumio[13982]: info: Stoppping Tunnel 1 Mar 26 03:13:52 katarynka sudo[14395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 26 03:13:52 katarynka sudo[14395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:52 katarynka sudo[14397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 26 03:13:52 katarynka sudo[14397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka sudo[14395]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:52 katarynka volumio[13982]: error: Cannot start Volumio Streaming Daemon Mar 26 03:13:52 katarynka volumio[13982]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 26 03:13:52 katarynka volumio[13982]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 26 03:13:52 katarynka sudo[14397]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:52 katarynka volumio[13982]: info: Remote SSH Stopped Mar 26 03:13:52 katarynka volumio[13982]: info: Setting Geolocation for MyVolumio to eu6 Mar 26 03:13:52 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:52 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:52 katarynka volumio[13982]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:13:53 katarynka volumio[13982]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 26 03:13:53 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:53 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:53 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:53 katarynka volumio[13982]: info: Getting Spotify volume Mar 26 03:13:53 katarynka volumio[13982]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:13:53 katarynka volumio[13982]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:13:53 katarynka volumio[13982]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 26 03:13:53 katarynka volumio[13982]: errno: -111, Mar 26 03:13:53 katarynka volumio[13982]: code: 'ECONNREFUSED', Mar 26 03:13:53 katarynka volumio[13982]: syscall: 'connect', Mar 26 03:13:53 katarynka volumio[13982]: address: '127.0.0.1', Mar 26 03:13:53 katarynka volumio[13982]: port: 9879, Mar 26 03:13:53 katarynka volumio[13982]: response: undefined Mar 26 03:13:53 katarynka volumio[13982]: } Mar 26 03:13:53 katarynka volumio[13982]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:13:54 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Mar 26 03:13:54 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:54 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:54 katarynka go-librespot[14412]: go-librespot daemon starting... Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=debug msg="app state loaded" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=info msg="zeroconf server listening on port 46247" Mar 26 03:13:54 katarynka go-librespot[14413]: time="2026-03-26T03:13:54+01:00" level=debug msg="obtained new client token: AACiLa93BL5A3Ms9vq+EapzzZxPKlWDE+e9JF0LRAqpq42MCGxUBHl02Wu3IHzVShReOhAQidJdP45UQYe7Xfz0c0iFsshFSWYjKKRRa1g45jrxsksWGzYmKAmXHFLfDXwL8ucdkOL9WY2YTPySqBQZvJQOK/LTo71WjH40y2VPnK8QLJzdnheO09xb9BPRF9f9LtKLlLytYVzy4DSY7oqZfEme3NfJKWsVXmyA3fbIhvFQ9gJcGVvnwpyU=" Mar 26 03:13:55 katarynka go-librespot[14413]: time="2026-03-26T03:13:55+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:55 katarynka go-librespot[14413]: time="2026-03-26T03:13:55+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:55 katarynka go-librespot[14413]: time="2026-03-26T03:13:55+01:00" level=debug msg="completed challenge" Mar 26 03:13:55 katarynka go-librespot[14413]: time="2026-03-26T03:13:55+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:55 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:55 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:55 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:55 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:55 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:55 katarynka sudo[14423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 03:12' Mar 26 03:13:55 katarynka sudo[14423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:13:55 katarynka sudo[14423]: pam_unix(sudo:session): session closed for user root Mar 26 03:13:56 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 26 03:13:56 katarynka volumio-remote-updater[686]: [2026-03-26 03:13:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 26 03:13:56 katarynka systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:56 katarynka systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 26 03:13:56 katarynka systemd[1]: volumio.service: Consumed 50.937s CPU time. Mar 26 03:13:56 katarynka systemd[1]: Started dynamicswap.service - dynamicswap service. Mar 26 03:13:56 katarynka systemd[1]: volumio.service: Scheduled restart job, restart counter is at 673. Mar 26 03:13:56 katarynka systemd[1]: Stopped volumio.service - Volumio Backend Module. Mar 26 03:13:56 katarynka systemd[1]: volumio.service: Consumed 50.937s CPU time. Mar 26 03:13:56 katarynka systemd[1]: Started volumio.service - Volumio Backend Module. Mar 26 03:13:56 katarynka systemd[1]: dynamicswap.service: Deactivated successfully. Mar 26 03:13:57 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:57 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:57 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:13:58 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Mar 26 03:13:58 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:58 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:13:58 katarynka go-librespot[14444]: go-librespot daemon starting... Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=debug msg="app state loaded" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=info msg="zeroconf server listening on port 38273" Mar 26 03:13:58 katarynka go-librespot[14445]: time="2026-03-26T03:13:58+01:00" level=debug msg="obtained new client token: AADYl38Oca0cWUnk07MFcDiXrac2I+qZSymOVkGve9W1mKFzvR13R+qyBlJ8NpOQ2wCttkhJJBvjam+BVR7sr0+KjvBrdg1iVL6ghs7+k06c03Wf3aO+kmOwxU2n3iU9lJYVDlTn2tanClQgISDpgje5OERz3K6IqbcjpCdp9yCdFGSOFY8uZIpZV/5N5Q1iOE8g9Jv3feSQegTRz6QOX634El++iV4sJC+rB7f0X/lA7LcewpfSs6Ap7C0=" Mar 26 03:13:59 katarynka go-librespot[14445]: time="2026-03-26T03:13:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:13:59 katarynka go-librespot[14445]: time="2026-03-26T03:13:59+01:00" level=debug msg="completed keyexchange" Mar 26 03:13:59 katarynka go-librespot[14445]: time="2026-03-26T03:13:59+01:00" level=debug msg="completed challenge" Mar 26 03:13:59 katarynka go-librespot[14445]: time="2026-03-26T03:13:59+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:13:59 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:13:59 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:13:59 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:13:59 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:13:59 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:00 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:00 katarynka volumio[14434]: info: ----- Volumio3 ---- Mar 26 03:14:00 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:00 katarynka volumio[14434]: info: ----- System startup ---- Mar 26 03:14:00 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:01 katarynka volumio-remote-updater[686]: [2026-03-26 03:14:01] [connect] Successful connection Mar 26 03:14:01 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:01 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:01 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:01 katarynka volumio[14434]: info: MYVOLUMIO Environment detected Mar 26 03:14:01 katarynka volumio[14434]: info: Plugin folders cleanup Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning into folder /volumio/app/plugins/ Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category audio_interface Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category miscellanea Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category music_service Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category plugins.json Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category system_controller Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category user_interface Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning into folder /data/plugins/ Mar 26 03:14:01 katarynka volumio[14434]: info: Scanning category music_service Mar 26 03:14:01 katarynka volumio[14434]: info: Plugin folders cleanup completed Mar 26 03:14:01 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:01 katarynka volumio[14434]: info: ----- Core plugins startup ---- Mar 26 03:14:01 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:01 katarynka volumio[14434]: info: Loading plugins from folder /volumio/app/plugins/ Mar 26 03:14:01 katarynka volumio[14434]: info: Adding plugin upnp to MyMusic Plugins Mar 26 03:14:01 katarynka volumio[14434]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 26 03:14:01 katarynka volumio[14434]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 26 03:14:01 katarynka volumio[14434]: info: Loading plugins from folder /data/plugins/ Mar 26 03:14:01 katarynka volumio[14434]: info: Loading plugin "system"... Mar 26 03:14:01 katarynka volumio[14434]: info: Loading plugin "appearance"... Mar 26 03:14:02 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Mar 26 03:14:02 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:02 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:02 katarynka go-librespot[14467]: go-librespot daemon starting... Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=debug msg="app state loaded" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=info msg="zeroconf server listening on port 42405" Mar 26 03:14:02 katarynka go-librespot[14468]: time="2026-03-26T03:14:02+01:00" level=debug msg="obtained new client token: AAASsm18RNedBfYgB30INygJWgP+CHwT897aA7hHDw0gml6zNdD6dfforx/3OWrH9A/O++UMhdZz8zlEMNr59r+FFnexNsJ3lmb9JpWK467ir9lRr2S+RgdSW3CY2WERuJrnEzz6/94k9Y5l8MQGZjKxNoJ2aqvBodxH+pVkTJAc+n/p0lsSxPz5WIyfDSwTRvAQmY+At2z/9j4JwhpbDItfSMx4y+ta1MokySrLI78aVMrM/yg8C57v+Bk=" Mar 26 03:14:03 katarynka go-librespot[14468]: time="2026-03-26T03:14:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:03 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:03 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:03 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:03 katarynka go-librespot[14468]: time="2026-03-26T03:14:03+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:03 katarynka go-librespot[14468]: time="2026-03-26T03:14:03+01:00" level=debug msg="completed challenge" Mar 26 03:14:03 katarynka volumio[14434]: info: Loading plugin "network"... Mar 26 03:14:03 katarynka go-librespot[14468]: time="2026-03-26T03:14:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:03 katarynka volumio[14434]: info: Refreshing Cached IP Addresses Mar 26 03:14:03 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:03 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:03 katarynka sudo[14477]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 03:14:03 katarynka sudo[14477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:03 katarynka volumio[14434]: info: Loading plugin "services"... Mar 26 03:14:03 katarynka sudo[14479]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 03:14:03 katarynka sudo[14477]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:03 katarynka volumio[14434]: info: Loading plugin "alsa_controller"... Mar 26 03:14:03 katarynka sudo[14479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:03 katarynka sudo[14479]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:03 katarynka sudo[14491]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 26 03:14:03 katarynka sudo[14491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:04 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "wizard"... Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "networkfs"... Mar 26 03:14:04 katarynka volumio[14434]: info: Starting Udev Watcher for removable devices Mar 26 03:14:04 katarynka volumio[14434]: info: Ignoring mount for partition: boot Mar 26 03:14:04 katarynka volumio[14434]: info: Ignoring mount for partition: volumio Mar 26 03:14:04 katarynka volumio[14434]: info: Ignoring mount for partition: volumio_data Mar 26 03:14:04 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "volumio_command_line_client"... Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "upnp"... Mar 26 03:14:04 katarynka volumio[14434]: info: [1774491244228] Starting Upmpd Daemon Mar 26 03:14:04 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "my_music"... Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "mpd"... Mar 26 03:14:04 katarynka sudo[14491]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:04 katarynka volumio[14434]: info: Loading plugin "upnp_browser"... Mar 26 03:14:05 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:05 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:05 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:06 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Mar 26 03:14:06 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:07 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:07 katarynka go-librespot[14511]: go-librespot daemon starting... Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="app state loaded" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:07 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:07 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:07 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=info msg="zeroconf server listening on port 39367" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="obtained new client token: AAA0dJb39BIztLSXIvSNSy5adx94/isA5OhXWxYbwZMK0vA1K4JTtiusp/CCz9Vd62R2/pgmtkhEMXqhjlGX2Hllvoy0GkC7MgMhn5tIucu1lTuJeDcWrBxkiakchZW7S4QN6x/HDjlpWKL9LO6Oh3a0ItlkdU7Vp/r+L4NDCJnGr6EdjnKvSj3jQuVJEpwryK8lby+LTvwF3OmfIXFTqZ8nfQsTG2RdpCwDHsyXk3qFBgKvBK75uZXz6yU=" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=debug msg="completed challenge" Mar 26 03:14:07 katarynka go-librespot[14512]: time="2026-03-26T03:14:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:07 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:07 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:07 katarynka volumio[14434]: info: Starting UPNP Browser Mar 26 03:14:07 katarynka volumio[14434]: info: Loading plugin "alarm-clock"... Mar 26 03:14:08 katarynka volumio[14434]: info: Loading plugin "airplay_emulation"... Mar 26 03:14:08 katarynka volumio[14434]: info: Starting Shairport Sync Mar 26 03:14:08 katarynka volumio[14434]: info: Loading plugin "last_100"... Mar 26 03:14:08 katarynka volumio[14434]: info: Loading plugin "webradio"... Mar 26 03:14:08 katarynka volumio[14434]: info: Loading plugin "i2s_dacs"... Mar 26 03:14:08 katarynka volumio[14434]: info: Loading plugin "volumiodiscovery"... Mar 26 03:14:08 katarynka volumio[14434]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 03:14:08 katarynka volumio[14434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:14:08 katarynka volumio[14434]: *** WARNING *** For more information see Mar 26 03:14:08 katarynka volumio[14434]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 03:14:08 katarynka volumio[14434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:14:08 katarynka volumio[14434]: *** WARNING *** For more information see Mar 26 03:14:08 katarynka node[14434]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 26 03:14:08 katarynka node[14434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:14:08 katarynka node[14434]: *** WARNING *** For more information see Mar 26 03:14:08 katarynka node[14434]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 26 03:14:08 katarynka node[14434]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 26 03:14:08 katarynka node[14434]: *** WARNING *** For more information see Mar 26 03:14:08 katarynka volumio[14434]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 26 03:14:08 katarynka volumio[14434]: info: Discovery: Started advertising with name: katarynka Mar 26 03:14:08 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 26 03:14:08 katarynka volumio[14434]: info: Loading plugin "spop"... Mar 26 03:14:09 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:09 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:09 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:10 katarynka volumio[14434]: info: Loading plugin "outputs"... Mar 26 03:14:10 katarynka volumio[14434]: info: Loading plugin "albumart"... Mar 26 03:14:10 katarynka volumio[14434]: info: Plugin example_plugin is not enabled Mar 26 03:14:10 katarynka volumio[14434]: info: Loading plugin "inputs"... Mar 26 03:14:10 katarynka volumio[14434]: info: Loading plugin "updater_comm"... Mar 26 03:14:10 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Mar 26 03:14:10 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:11 katarynka volumio[14434]: info: Plugin mpdemulation is not enabled Mar 26 03:14:11 katarynka volumio[14434]: info: Loading plugin "rest_api"... Mar 26 03:14:11 katarynka volumio[14434]: info: Loading plugin "websocket"... Mar 26 03:14:11 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:11 katarynka volumio[14434]: info: Starting Socket.io Server version 1.7.4 Mar 26 03:14:11 katarynka go-librespot[14530]: go-librespot daemon starting... Mar 26 03:14:11 katarynka volumio[14434]: info: Loading i18n strings for locale pl Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:11 katarynka volumio[14434]: Updating browse sources language Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=debug msg="app state loaded" Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:11 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:11 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:11 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::initPlayerControls Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: Express server listening on port 3000 Mar 26 03:14:11 katarynka volumio[14434]: [Metrics] WebUI: 12s 455.02ms Mar 26 03:14:11 katarynka volumio[14434]: info: CoreStateMachine::resetVolumioState Mar 26 03:14:11 katarynka volumio[14434]: info: CoreStateMachine::getcurrentVolume Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 03:14:11 katarynka volumio[14434]: info: Cannot read play queue from file Mar 26 03:14:11 katarynka volumio[14434]: info: Volumio Network Manager: Network status updated: 2 Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=info msg="zeroconf server listening on port 43093" Mar 26 03:14:11 katarynka go-librespot[14532]: time="2026-03-26T03:14:11+01:00" level=debug msg="obtained new client token: AAAWami9OP56mJBJeeXWeWys7cSiK6njwyo1+5n00X9QsYORJUplDoCDedQNKkkM7hAxv9j4lTiM+O/b84lS+G5jF+Bwv3umafS5kFI+mFt/R/HEcM7GqxhHJriZ4uFgxLcycQ56OpzCIG7o/jUR8NKsfAqe7CgE1eQlnOjMn/uK3IjCKchwHWl0nkyqU6gJWtN+dNe+Ith4UkCD2zwFZUnv3MbVsOZMGEBZi2DI5QDFxA7x4FwYMI/rF5A=" Mar 26 03:14:11 katarynka volumio[14521]: Forking 3 albumart workers Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:11 katarynka volumio[14434]: info: CoreStateMachine::setRepeat null single undefined Mar 26 03:14:11 katarynka volumio[14434]: info: CoreStateMachine::pushState Mar 26 03:14:11 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::volumioPushState Mar 26 03:14:11 katarynka volumio[14434]: info: CoreStateMachine::setRandom null Mar 26 03:14:11 katarynka volumio[14434]: info: CoreStateMachine::pushState Mar 26 03:14:11 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:11 katarynka volumio[14434]: info: CoreCommandRouter::volumioPushState Mar 26 03:14:12 katarynka volumio[14434]: info: VolumeController:: Volume=96 Mute =false Mar 26 03:14:12 katarynka volumio[14434]: info: CoreStateMachine::pushState Mar 26 03:14:12 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::volumioPushState Mar 26 03:14:12 katarynka volumio[14434]: info: CoreStateMachine::updateTrackBlock Mar 26 03:14:12 katarynka volumio[14434]: info: CorePlayQueue::getTrackBlock Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::volumioRetrievevolume Mar 26 03:14:12 katarynka volumio[14434]: info: Setting Device type: Raspberry PI Mar 26 03:14:12 katarynka volumio[14434]: info: Completed loading Core Plugins Mar 26 03:14:12 katarynka volumio[14434]: info: Preparing to generate the ALSA configuration file Mar 26 03:14:12 katarynka go-librespot[14532]: time="2026-03-26T03:14:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:12 katarynka volumio[14434]: info: Asound.conf file unchanged, so no further update is needed Mar 26 03:14:12 katarynka volumio[14434]: info: Output device has changed, restarting MPD Mar 26 03:14:12 katarynka volumio[14434]: info: Output device has changed, restarting Shairport Sync Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:12 katarynka sudo[14584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 03:14:12 katarynka sudo[14584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:12 katarynka go-librespot[14532]: time="2026-03-26T03:14:12+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:12 katarynka sudo[14584]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:12 katarynka go-librespot[14532]: time="2026-03-26T03:14:12+01:00" level=debug msg="completed challenge" Mar 26 03:14:12 katarynka sudo[14586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 03:14:12 katarynka volumio[14434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 03:14:12 katarynka sudo[14586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:12 katarynka volumio[14434]: info: ___________ START PLUGINS ___________ Mar 26 03:14:12 katarynka volumio[14434]: info: ControllerMpd::onStart: Initializing MPD Mar 26 03:14:12 katarynka volumio[14434]: info: Creating MPD Configuration file Mar 26 03:14:12 katarynka sudo[14594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 26 03:14:12 katarynka sudo[14594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 03:14:12 katarynka volumio[14434]: info: [1774491252612] CoreMusicLibrary::Adding element Serwery Mediów Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:14:12 katarynka systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 26 03:14:12 katarynka sudo[14594]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:12 katarynka volumio[14434]: info: UPNP Browser: Client initialized successfully Mar 26 03:14:12 katarynka sudo[14596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 26 03:14:12 katarynka sudo[14596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:12 katarynka go-librespot[14532]: time="2026-03-26T03:14:12+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:12 katarynka systemd[1]: mpd.service: Deactivated successfully. Mar 26 03:14:12 katarynka systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 03:14:12 katarynka systemd[1]: mpd.service: Consumed 7.786s CPU time. Mar 26 03:14:12 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:12 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:12 katarynka systemd[1]: mpd.socket: Deactivated successfully. Mar 26 03:14:12 katarynka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 03:14:12 katarynka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:12 katarynka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 03:14:12 katarynka volumio[14434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:12 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:12 katarynka systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 03:14:12 katarynka volumio[14434]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 26 03:14:12 katarynka systemd[1]: mpd.service: Deactivated successfully. Mar 26 03:14:13 katarynka systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 03:14:13 katarynka volumio[14434]: info: [1774491253008] CoreMusicLibrary::Adding element Last_100 Mar 26 03:14:13 katarynka systemd[1]: mpd.socket: Deactivated successfully. Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:14:13 katarynka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 26 03:14:13 katarynka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 26 03:14:13 katarynka volumio[14434]: info: [1774491253030] CoreMusicLibrary::Adding element Webradio Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 03:14:13 katarynka volumio[14434]: info: Initializing BBC Radios Mar 26 03:14:13 katarynka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 26 03:14:13 katarynka systemd[1]: Starting mpd.service - Music Player Daemon... Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:13 katarynka volumio[14434]: info: Creating Spotify config file Mar 26 03:14:13 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:13 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:13 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:13 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:13 katarynka volumio-remote-updater[686]: [2026-03-26 03:14:13] [connect] Successful connection Mar 26 03:14:13 katarynka sudo[14614]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 26 03:14:13 katarynka sudo[14614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 26 03:14:13 katarynka sudo[14614]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:14 katarynka volumio[14434]: info: Volumio Calling Home Mar 26 03:14:15 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:15 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:15 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:15 katarynka volumio[14434]: info: Discovery: adding 4d616d77-6a8c-4da2-85a4-840d70f98e51 Mar 26 03:14:15 katarynka volumio[14434]: info: Discovery: Found device katarynka Mar 26 03:14:15 katarynka volumio[14434]: info: CoreCommandRouter::volumioGetState Mar 26 03:14:15 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:15 katarynka sudo[14644]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 26 03:14:15 katarynka volumio[14434]: info: Discovery: this is already registered, 4d616d77-6a8c-4da2-85a4-840d70f98e51 Mar 26 03:14:15 katarynka volumio[14434]: info: Discovery: Found device katarynka Mar 26 03:14:15 katarynka volumio[14434]: info: CoreCommandRouter::volumioGetState Mar 26 03:14:15 katarynka sudo[14644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:15 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:15 katarynka sudo[14644]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:15 katarynka volumio[14551]: Starting albumart workers Mar 26 03:14:15 katarynka sudo[14647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 26 03:14:15 katarynka sudo[14647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:15 katarynka sudo[14647]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:15 katarynka volumio[14434]: info: MPD Permissions set Mar 26 03:14:15 katarynka volumio[14434]: info: MPD Permissions set Mar 26 03:14:15 katarynka volumio[14434]: info: VolumeController:: Volume=96 Mute =false Mar 26 03:14:15 katarynka volumio[14434]: info: CoreStateMachine::pushState Mar 26 03:14:15 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:15 katarynka volumio[14434]: info: CoreCommandRouter::volumioPushState Mar 26 03:14:15 katarynka volumio[14550]: Starting albumart workers Mar 26 03:14:15 katarynka volumio-remote-updater[686]: [2026-03-26 03:14:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774491253 101 Mar 26 03:14:15 katarynka volumio[14434]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Mar 26 03:14:15 katarynka volumio[14434]: info: Volumio called home Mar 26 03:14:15 katarynka volumio[14434]: info: Spotify config file written Mar 26 03:14:15 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Mar 26 03:14:15 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:16 katarynka sudo[14651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 26 03:14:16 katarynka sudo[14651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:16 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:16 katarynka go-librespot[14652]: go-librespot daemon starting... Mar 26 03:14:16 katarynka volumio[14549]: Starting albumart workers Mar 26 03:14:16 katarynka go-librespot[14654]: time="2026-03-26T03:14:16+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:16 katarynka go-librespot[14654]: time="2026-03-26T03:14:16+01:00" level=debug msg="app state loaded" Mar 26 03:14:16 katarynka go-librespot[14654]: time="2026-03-26T03:14:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:16 katarynka systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 26 03:14:16 katarynka systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 26 03:14:16 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:16 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:16 katarynka go-librespot[14660]: go-librespot daemon starting... Mar 26 03:14:16 katarynka sudo[14651]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:16 katarynka go-librespot[14661]: time="2026-03-26T03:14:16+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:16 katarynka go-librespot[14661]: time="2026-03-26T03:14:16+01:00" level=debug msg="app state loaded" Mar 26 03:14:16 katarynka go-librespot[14661]: time="2026-03-26T03:14:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:16 katarynka volumio[14434]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 03:14:16 katarynka volumio[14434]: info: No need to fix Spotify hosts Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=info msg="zeroconf server listening on port 37885" Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=debug msg="obtained new client token: AADJ5RH/fFvkGqq4PekVqJk0N9j/P3M3NfTLwwYWocJdUHXa5l5wOkb+/pddqTPy8rQRDp0MZi/9F99qX7YeNiIX2FW3vdTK6g8F6DjtcYt1ev5FiuAM5b7jTXrN2BLPTUEa32LhqYClbMTc8iQbqjHJN0Wy5xvM0EZI9IjQJIeWgl2uWBAY4nhhjvYlqVE9bOcDRvgIJdlcO2mlkoFp5IOPmtkf809Y5tU0eBUbnLe/4a3+U/0yYany" Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:17 katarynka volumio[14434]: info: Starting Shairport Sync Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=debug msg="completed challenge" Mar 26 03:14:17 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:17 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:17 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:17 katarynka volumio[14434]: info: Starting Shairport Sync Mar 26 03:14:17 katarynka go-librespot[14661]: time="2026-03-26T03:14:17+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:17 katarynka volumio[14434]: info: Starting Shairport Sync Mar 26 03:14:17 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:17 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:17 katarynka sudo[14679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 03:14:17 katarynka sudo[14679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:17 katarynka sudo[14681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 03:14:17 katarynka sudo[14681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:17 katarynka sudo[14683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 26 03:14:17 katarynka sudo[14683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:17 katarynka sudo[14686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 26 03:14:17 katarynka sudo[14686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:17 katarynka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 26 03:14:17 katarynka systemd[1]: shairport-sync.service: Deactivated successfully. Mar 26 03:14:17 katarynka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 03:14:17 katarynka systemd[1]: shairport-sync.service: Consumed 2.412s CPU time. Mar 26 03:14:17 katarynka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 26 03:14:18 katarynka sudo[14679]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:18 katarynka sudo[14683]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:18 katarynka sudo[14681]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:18 katarynka sudo[14686]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:18 katarynka volumio[14434]: info: An error occurred while refreshing Spotify Token Error: Bad Request Mar 26 03:14:18 katarynka volumio[14434]: info: Shairport-Sync Started Mar 26 03:14:18 katarynka volumio[14434]: Error adding Membership: Error: addMembership EINVAL Mar 26 03:14:18 katarynka volumio[14434]: info: Shairport-Sync Started Mar 26 03:14:18 katarynka volumio[14434]: info: Shairport-Sync Started Mar 26 03:14:18 katarynka volumio[14434]: info: CoreCommandRouter::volumioGetState Mar 26 03:14:18 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:18 katarynka volumio[14434]: info: Upmpdcli Daemon Started Mar 26 03:14:19 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:19 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:19 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:20 katarynka volumio[14434]: info: go-librespot daemon successfully initialized Mar 26 03:14:20 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 26 03:14:20 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:20 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:20 katarynka go-librespot[14724]: go-librespot daemon starting... Mar 26 03:14:20 katarynka go-librespot[14725]: time="2026-03-26T03:14:20+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:20 katarynka go-librespot[14725]: time="2026-03-26T03:14:20+01:00" level=debug msg="app state loaded" Mar 26 03:14:20 katarynka go-librespot[14725]: time="2026-03-26T03:14:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=info msg="zeroconf server listening on port 38477" Mar 26 03:14:21 katarynka mpd[14625]: 2026-03-26T03:14:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 26 03:14:21 katarynka systemd[1]: Started mpd.service - Music Player Daemon. Mar 26 03:14:21 katarynka sudo[14596]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:21 katarynka sudo[14586]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:21 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:21 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:21 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:21 katarynka volumio[14434]: info: Completed starting Core Plugins Mar 26 03:14:21 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:21 katarynka volumio[14434]: info: ----- MyVolumio plugins startup ---- Mar 26 03:14:21 katarynka volumio[14434]: info: ------------------------------------------- Mar 26 03:14:21 katarynka volumio[14434]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="obtained new client token: AABBvNX064fW8wm1E6QeyLnGnxVqOFrNYKH9P/NdNhybDu+yCLtaF7YZklZp4n7v2hW5eb2w9atH4uwgi4GIxUUkCRaJpmAUEGdJ2PT1q7FAMi736pVM0SRVCX0Vt9BnwvayEybaUlGG1QY4tI33mhg0+YjeU2NPehkaeA36jzuiwPKPLH9Ljw/Og7aNJ44lAyQym9xTVEyBLFKH/AT3tecadHHmR3cS+xPkIGMPsT67qDykPQrIZi8U2Dk=" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 26 03:14:21 katarynka volumio[14434]: error: MPD error: The expression evaluated to a falsy value: Mar 26 03:14:21 katarynka volumio[14434]: assert.ok(self.idling) Mar 26 03:14:21 katarynka volumio[14434]: error: The expression evaluated to a falsy value: Mar 26 03:14:21 katarynka volumio[14434]: assert.ok(self.idling) Mar 26 03:14:21 katarynka volumio[14434]: error: updateQueue error: null Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14:21+01:00" level=debug msg="completed challenge" Mar 26 03:14:21 katarynka volumio[14434]: info: MPD running with PID14625 Mar 26 03:14:21 katarynka volumio[14434]: ,establishing connection Mar 26 03:14:21 katarynka volumio[14434]: error: updateQueue error: null Mar 26 03:14:21 katarynka go-librespot[14725]: time="2026-03-26T03:14: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 26 03:14:21 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:21 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:23 katarynka volumio[14434]: info: Initializing connection to go-librespot Websocket Mar 26 03:14:23 katarynka volumio[14434]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:14:23 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:23 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:23 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:24 katarynka sudo[14755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 26 03:14:24 katarynka sudo[14755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:24 katarynka sudo[14757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Mar 26 03:14:24 katarynka sudo[14757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:24 katarynka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Mar 26 03:14:24 katarynka systemd[1]: mpd_monitor.service: Deactivated successfully. Mar 26 03:14:24 katarynka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Mar 26 03:14:24 katarynka systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Mar 26 03:14:24 katarynka sudo[14755]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:24 katarynka volumio[14434]: info: Successfully started MPD Monitor Mar 26 03:14:24 katarynka mpd_monitor.sh[14760]: MPD Monitor Service: Starting MPD Monitor Service Mar 26 03:14:24 katarynka sudo[14757]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:24 katarynka volumio[14434]: info: Successfully started MPD Monitor Mar 26 03:14:24 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 26 03:14:24 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:24 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:24 katarynka go-librespot[14764]: go-librespot daemon starting... Mar 26 03:14:24 katarynka go-librespot[14765]: time="2026-03-26T03:14:24+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:24 katarynka go-librespot[14765]: time="2026-03-26T03:14:24+01:00" level=debug msg="app state loaded" Mar 26 03:14:24 katarynka go-librespot[14765]: time="2026-03-26T03:14:24+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=info msg="zeroconf server listening on port 46813" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=debug msg="obtained new client token: AACYCEII2l/zWWMB+NNLRFvKujazhtCFL8KdwHSWRz+nZib2afQMmZdV5Uhph0Pw3nesuDK1XZaBPiI0Fz5mAoUyLRF0FGkWW23emYsL1TSzF3i0U1RJQPwJwnVtcKqtcfUcLTauJ7jn2B8EYJLIt6Soj3YzmmJKg2NstdEHBYi/btW1x3IfaEXmtN5ZqlLhgOtI4Y+6XjagjA5V1GbBoeW4xMQb/m4iX2nZgSJuIYwgBzMLJuRyqkjr1MU=" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:25 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:25 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:25 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=debug msg="completed challenge" Mar 26 03:14:25 katarynka go-librespot[14765]: time="2026-03-26T03:14:25+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:25 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:25 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:26 katarynka volumio[14434]: info: Initializing connection to go-librespot Websocket Mar 26 03:14:26 katarynka volumio[14434]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 26 03:14:26 katarynka volumio[14434]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 26 03:14:27 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:27 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:27 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:28 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 26 03:14:28 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:28 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:28 katarynka go-librespot[14772]: go-librespot daemon starting... Mar 26 03:14:28 katarynka go-librespot[14773]: time="2026-03-26T03:14:28+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:28 katarynka go-librespot[14773]: time="2026-03-26T03:14:28+01:00" level=debug msg="app state loaded" Mar 26 03:14:28 katarynka go-librespot[14773]: time="2026-03-26T03:14:28+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14: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 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14: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 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14: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 26 03:14:29 katarynka volumio[14434]: info: Initializing connection to go-librespot Websocket Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14:29+01:00" level=info msg="zeroconf server listening on port 38563" Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14:29+01:00" level=debug msg="new websocket client" Mar 26 03:14:29 katarynka volumio[14434]: info: Connection to go-librespot Websocket established Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14:29+01:00" level=debug msg="obtained new client token: AADmZTTdA98DbIwvKyknudfyyS0JCPR+iDGpa/jwMeAeN+LyZqkHJzqZCsrYHM3ZSVLmdgLmfH68LDiqmDcHJuIkvni9+2ihKzVRCC0T6Kw7/JEWepTWii+TJaqSIgYsqxYtbx0GPijYGsDVltbEo5FM+TtNYkFgM34WI7iijwNkO5E4vGv8JArdUbrdadE+b6isAWCl668oK6UFf8vXOde1xwgrUhvx/QH8eTMjTe8sqUwRC5/enseDlU0=" Mar 26 03:14:29 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:29 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:29 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin bluetooth to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin multiroom to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin metavolumio to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin cd_controller to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 26 03:14:29 katarynka volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14:29+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:29 katarynka go-librespot[14773]: time="2026-03-26T03:14:29+01:00" level=debug msg="completed challenge" Mar 26 03:14:30 katarynka go-librespot[14773]: time="2026-03-26T03:14:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:30 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:30 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:31 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:31 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:31 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:31 katarynka volumio[14434]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 26 03:14:31 katarynka volumio[14434]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 26 03:14:31 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:31 katarynka volumio[14434]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 26 03:14:31 katarynka volumio[14434]: info: Starting MyVolumio Remote Streaming Endpoints Mar 26 03:14:31 katarynka volumio[14434]: info: MyVolumio login type: Token Mar 26 03:14:31 katarynka volumio[14434]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 26 03:14:31 katarynka volumio[14434]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 26 03:14:33 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:33 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:33 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:33 katarynka systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 26 03:14:33 katarynka systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:33 katarynka systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 26 03:14:33 katarynka go-librespot[14780]: go-librespot daemon starting... Mar 26 03:14:33 katarynka go-librespot[14781]: time="2026-03-26T03:14:33+01:00" level=info msg="running go-librespot 0.4.0" Mar 26 03:14:33 katarynka go-librespot[14781]: time="2026-03-26T03:14:33+01:00" level=debug msg="app state loaded" Mar 26 03:14:33 katarynka go-librespot[14781]: time="2026-03-26T03:14:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 03:14:33 katarynka volumio[14434]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 26 03:14:33 katarynka volumio[14434]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 26 03:14:33 katarynka volumio[14434]: info: Streaming services startup Mar 26 03:14:33 katarynka volumio[14434]: info: Starting Streaming Daemon Mar 26 03:14:33 katarynka sudo[14789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 26 03:14:33 katarynka sudo[14789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 26 03:14:33 katarynka volumio[14434]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 26 03:14:33 katarynka sudo[14789]: pam_unix(sudo:session): session closed for user root Mar 26 03:14:33 katarynka volumio[14434]: info: Getting Spotify volume Mar 26 03:14:33 katarynka volumio[14434]: info: Connection to go-librespot Websocket closed Mar 26 03:14:33 katarynka volumio[14434]: error: Cannot start Volumio Streaming Daemon Mar 26 03:14:33 katarynka volumio[14434]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 26 03:14:33 katarynka volumio[14434]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 26 03:14:34 katarynka volumio[14434]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14: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 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14: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 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14: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 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14:34+01:00" level=info msg="zeroconf server listening on port 45345" Mar 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14:34+01:00" level=debug msg="obtained new client token: AAAvtvYzp0VtKGkcXGD53dm7cI53JqZNpli7k2Ldc9yi+pkilf1QWOwD/LVIe4/DD4RPH3VWCTyW4CbjtFVrwz7HUYC8+dNniRCD2XRtJ/dWrUk3knbHoZShu7//Ng5ETePi9TXxXJm+XNZMyqkJaxkTRZ/SzE4zDL43DpwwAxRAIazFei4tlYZY/zYfh8pdDJQ/MM8nBFLsfS8UNp/TZ6qDqrYkR/+eWYe4KyZibT0lwqj34yovg4ah" Mar 26 03:14:34 katarynka volumio[14434]: info: CoreCommandRouter::volumioGetState Mar 26 03:14:34 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14:34+01:00" level=debug msg="completed keyexchange" Mar 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14:34+01:00" level=debug msg="completed challenge" Mar 26 03:14:34 katarynka volumio[14434]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Mar 26 03:14:34 katarynka volumio[14434]: SPOTIFY: SPOTIFY VOLUME undefined Mar 26 03:14:34 katarynka volumio[14434]: SPOTIFY: VOLUMIO VOLUME 96 Mar 26 03:14:34 katarynka volumio[14434]: info: Aligning Spotify Volume to Volumio Volume Mar 26 03:14:34 katarynka volumio[14434]: info: CoreCommandRouter::volumioGetState Mar 26 03:14:34 katarynka volumio[14434]: info: CorePlayQueue::getTrack 0 Mar 26 03:14:34 katarynka volumio[14434]: info: Setting Spotify Volume from Volumio: 96 Mar 26 03:14:34 katarynka go-librespot[14781]: time="2026-03-26T03:14:34+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Mar 26 03:14:34 katarynka systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 26 03:14:34 katarynka systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 26 03:14:34 katarynka volumio[14434]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:14:34 katarynka volumio[14434]: Error: socket hang up Mar 26 03:14:34 katarynka volumio[14434]: at connResetException (node:internal/errors:720:14) Mar 26 03:14:34 katarynka volumio[14434]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 26 03:14:34 katarynka volumio[14434]: at Socket.emit (node:events:526:35) Mar 26 03:14:34 katarynka volumio[14434]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 26 03:14:34 katarynka volumio[14434]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 26 03:14:34 katarynka volumio[14434]: code: 'ECONNRESET', Mar 26 03:14:34 katarynka volumio[14434]: response: undefined Mar 26 03:14:34 katarynka volumio[14434]: } Mar 26 03:14:34 katarynka volumio[14434]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 03:14:35 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Mar 26 03:14:35 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Mar 26 03:14:35 katarynka upmpdcli[1473]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Mar 26 03:14:36 katarynka sudo[14824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-26 03:13' Mar 26 03:14:36 katarynka sudo[14824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"