Jan 26 14:55:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 26 14:55:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:00 volumio go-librespot[31557]: go-librespot daemon starting... Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=debug msg="app state loaded" Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 14:55:00 volumio go-librespot[31558]: time="2026-01-26T14:55:00+07:00" level=info msg="zeroconf server listening on port 33749" Jan 26 14:55:01 volumio volumio[31235]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 14:55:01 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:01 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:01 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:01 volumio go-librespot[31558]: time="2026-01-26T14:55:01+07:00" level=debug msg="obtained new client token: AAA+zucKE+UNazTRzLx3ThLfuPJTH/p3mgKoPzUh4tKegdBWl9JjBxNqNlYqps5gmZhHVz8JCdHMDcZMcjcLAYxJyfosXQJVvfAIMFIzbB8IalFpp3xRNXBNwajBpS0M3oAa0uagLawYzmqH+SaW/6kT7N5paJ4ETMFDyqGr112BBu28vTYq81XyRTsgWCrwlYubtbFujuVrLVu8Xky5qNBVYRe5bgxwGzI17kG1UFrUhS83b62BkBBF" Jan 26 14:55:01 volumio go-librespot[31558]: time="2026-01-26T14:55:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:01 volumio go-librespot[31558]: time="2026-01-26T14:55:01+07:00" level=debug msg="completed keyexchange" Jan 26 14:55:01 volumio go-librespot[31558]: time="2026-01-26T14:55:01+07:00" level=debug msg="completed challenge" Jan 26 14:55:01 volumio go-librespot[31558]: time="2026-01-26T14:55:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:55:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:01 volumio volumio[31235]: info: Initializing connection to go-librespot Websocket Jan 26 14:55:01 volumio volumio[31235]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:55:03 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:03 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:03 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 26 14:55:04 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:04 volumio go-librespot[31566]: go-librespot daemon starting... Jan 26 14:55:04 volumio go-librespot[31567]: time="2026-01-26T14:55:04+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:04 volumio go-librespot[31567]: time="2026-01-26T14:55:04+07:00" level=debug msg="app state loaded" Jan 26 14:55:04 volumio go-librespot[31567]: time="2026-01-26T14:55:04+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:04 volumio volumio[31235]: info: Initializing connection to go-librespot Websocket Jan 26 14:55:04 volumio go-librespot[31567]: time="2026-01-26T14:55:04+07:00" level=debug msg="new websocket client" Jan 26 14:55:04 volumio volumio[31235]: info: Connection to go-librespot Websocket established Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 14:55:05 volumio volumio[31235]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 14:55:05 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:05 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:05 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:05 volumio volumio[31235]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=info msg="zeroconf server listening on port 43175" Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=debug msg="obtained new client token: AABhYiEc7VhXsZ9vdt2M/AM3h7lMwzzfnxswWjSCStSm0vb9RRVsny+72qOqkE7Wm6OuxbF44k1DDfP5vuWXfAWtJD6kGxCLItVBFM0IjY9tszzBkUyp9dMn+HxlddMOqHVu/BKzl0fMJUuTI2ZPqVdGk1Ohb+iNU5KwSKJmNKHSg/6qW9C0KCRBVoquXHlQ24z7PaAApmx4WBf3bi7R7KLKDFo6J+egfHT56pDziop296wFbcKkyTN1Vxc=" Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:05 volumio go-librespot[31567]: time="2026-01-26T14:55:05+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:06 volumio volumio[31235]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 14:55:06 volumio volumio[31235]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 14:55:06 volumio volumio[31235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:06 volumio volumio[31235]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:06 volumio volumio[31235]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 14:55:06 volumio volumio[31235]: info: MyVolumio login type: Token Jan 26 14:55:07 volumio volumio[31235]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 14:55:07 volumio volumio[31235]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 14:55:07 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:07 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:07 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:08 volumio volumio[31235]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 14:55:08 volumio volumio[31235]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 14:55:08 volumio volumio[31235]: info: Streaming services startup Jan 26 14:55:08 volumio volumio[31235]: info: Starting Streaming Daemon Jan 26 14:55:08 volumio volumio[31235]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 14:55:08 volumio sudo[31589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 14:55:08 volumio sudo[31589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 26 14:55:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:08 volumio go-librespot[31595]: go-librespot daemon starting... Jan 26 14:55:08 volumio sudo[31589]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:08 volumio volumio[31235]: info: Getting Spotify volume Jan 26 14:55:08 volumio go-librespot[31596]: time="2026-01-26T14:55:08+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:08 volumio go-librespot[31596]: time="2026-01-26T14:55:08+07:00" level=debug msg="app state loaded" Jan 26 14:55:08 volumio volumio[31235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 14:55:08 volumio go-librespot[31596]: time="2026-01-26T14:55:08+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:08 volumio volumio[31235]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:55:08 volumio volumio[31235]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 26 14:55:08 volumio volumio[31235]: errno: -111, Jan 26 14:55:08 volumio volumio[31235]: code: 'ECONNREFUSED', Jan 26 14:55:08 volumio volumio[31235]: syscall: 'connect', Jan 26 14:55:08 volumio volumio[31235]: address: '127.0.0.1', Jan 26 14:55:08 volumio volumio[31235]: port: 9879, Jan 26 14:55:08 volumio volumio[31235]: response: undefined Jan 26 14:55:08 volumio volumio[31235]: } Jan 26 14:55:08 volumio volumio[31235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 14:55:09 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:09 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:09 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=info msg="zeroconf server listening on port 39647" Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=debug msg="obtained new client token: AABKvTS5YDcezpcJJwD0YUWEBrZg2MREhesySwlRVQCJKURTIey4iYsMoPcHLearjqEZlUKial4VxNobeP++n9OizhGt7uQdoOS6VTLeyg0AXJc/jhSI9U8LKGbP00OEa2VSBHvCRn4Eaa0/rW4nIJkL5pfZ7ahUwrdLxe2daaueqgLwtiXgkBrfL/wgGJ0rgHtZDCh/nvvoLGJip/L1/5wd37oi2l3oblLqQO97EA3VubGv1QrjKq7aQpE=" Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:09 volumio go-librespot[31596]: time="2026-01-26T14:55:09+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:10 volumio sudo[31617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 14:54' Jan 26 14:55:10 volumio sudo[31617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:10 volumio sudo[31617]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:10 volumio volumio-remote-updater[628]: [2026-01-26 14:55:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 26 14:55:10 volumio volumio-remote-updater[628]: [2026-01-26 14:55:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 26 14:55:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 26 14:55:10 volumio systemd[1]: volumio.service: Consumed 1min 6.240s CPU time. Jan 26 14:55:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 26 14:55:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 26 14:55:11 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:11 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:11 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7114. Jan 26 14:55:11 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 26 14:55:11 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 26 14:55:11 volumio systemd[1]: volumio.service: Consumed 1min 6.240s CPU time. Jan 26 14:55:11 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 26 14:55:11 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 26 14:55:13 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:13 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:13 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 26 14:55:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:13 volumio go-librespot[31649]: go-librespot daemon starting... Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=debug msg="app state loaded" Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:13 volumio go-librespot[31650]: time="2026-01-26T14:55:13+07:00" level=info msg="zeroconf server listening on port 43749" Jan 26 14:55:14 volumio go-librespot[31650]: time="2026-01-26T14:55:14+07:00" level=debug msg="obtained new client token: AAAwqJjUtLHr19i0rXYccJ6Yr7WXGfNd4MOIiwnjA0gwIBNlU4NDJFS2kMVwSrSYH/vfG7qiXwUYvdW5r+p9kiPhCtFSy3ElJ5J7/Ko6Vol2cPHtCP+TJz7ZTMM5M08IMvr67u2ymRUWTOZLwVuL8adaSnqXJOfoV9YCN0fWyYnKDAbtDsgYvqu/ng2hSQeUMtaZZReCEtZDFlFz9RiZlAoX7QPWp5xBwOkqfhWTLvIWTRZBnc0rtOrM" Jan 26 14:55:14 volumio go-librespot[31650]: time="2026-01-26T14:55:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:14 volumio go-librespot[31650]: time="2026-01-26T14:55:14+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:15 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:15 volumio volumio[31634]: info: ----- Volumio3 ---- Jan 26 14:55:15 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:15 volumio volumio[31634]: info: ----- System startup ---- Jan 26 14:55:15 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:15 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:15 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:15 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:15 volumio volumio-remote-updater[628]: [2026-01-26 14:55:15] [connect] Successful connection Jan 26 14:55:16 volumio volumio[31634]: info: MYVOLUMIO Environment detected Jan 26 14:55:16 volumio volumio[31634]: info: Plugin folders cleanup Jan 26 14:55:16 volumio volumio[31634]: info: Scanning into folder /volumio/app/plugins/ Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category audio_interface Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category miscellanea Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category music_service Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category plugins.json Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category system_controller Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category user_interface Jan 26 14:55:16 volumio volumio[31634]: info: Scanning into folder /data/plugins/ Jan 26 14:55:16 volumio volumio[31634]: info: Scanning category music_service Jan 26 14:55:16 volumio volumio[31634]: info: Plugin folders cleanup completed Jan 26 14:55:16 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:16 volumio volumio[31634]: info: ----- Core plugins startup ---- Jan 26 14:55:16 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:16 volumio volumio[31634]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 14:55:16 volumio volumio[31634]: info: Adding plugin upnp to MyMusic Plugins Jan 26 14:55:16 volumio volumio[31634]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 14:55:16 volumio volumio[31634]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 14:55:16 volumio volumio[31634]: info: Loading plugins from folder /data/plugins/ Jan 26 14:55:16 volumio volumio[31634]: info: Loading plugin "system"... Jan 26 14:55:16 volumio volumio[31634]: info: Loading plugin "appearance"... Jan 26 14:55:17 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:17 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:17 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 26 14:55:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:17 volumio go-librespot[31669]: go-librespot daemon starting... Jan 26 14:55:17 volumio go-librespot[31670]: time="2026-01-26T14:55:17+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:17 volumio go-librespot[31670]: time="2026-01-26T14:55:17+07:00" level=debug msg="app state loaded" Jan 26 14:55:17 volumio go-librespot[31670]: time="2026-01-26T14:55:17+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=info msg="zeroconf server listening on port 44119" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=debug msg="obtained new client token: AAAd0mAhzS+QWdYKhlOJubG72RCrbbL2kxXKcupktaZ7I6rluz1DUZWJgriITHg2RjuzYVXzMFtxpx5TdU9FmsH2u5U9m5fCzpQ/0EQMQGZmI9sPsg4ekvWOw2UJQ42EscU6MsT02uwqi0yCbs+tWK0zNptEUDWjGs6LmqlGgnfH0ts+vwm6wUt73cxXKFTh62ewdr5T4jpjtLNOleFOJhZEy3MrnzEOGzpbLKywU5K/2iWqmp0GKvensoc=" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:18 volumio go-librespot[31670]: time="2026-01-26T14:55:18+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:18 volumio volumio[31634]: info: Loading plugin "network"... Jan 26 14:55:18 volumio volumio[31634]: info: Refreshing Cached IP Addresses Jan 26 14:55:18 volumio sudo[31678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 14:55:18 volumio sudo[31678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:18 volumio sudo[31680]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 14:55:18 volumio sudo[31680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:18 volumio sudo[31678]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:18 volumio volumio[31634]: info: Loading plugin "services"... Jan 26 14:55:18 volumio volumio[31634]: info: Loading plugin "alsa_controller"... Jan 26 14:55:18 volumio sudo[31680]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:18 volumio sudo[31693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 26 14:55:18 volumio sudo[31693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:18 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 14:55:18 volumio volumio[31634]: info: Loading plugin "wizard"... Jan 26 14:55:18 volumio volumio[31634]: info: Loading plugin "networkfs"... Jan 26 14:55:18 volumio volumio[31634]: info: Starting Udev Watcher for removable devices Jan 26 14:55:18 volumio volumio[31634]: info: Ignoring mount for partition: boot Jan 26 14:55:18 volumio volumio[31634]: info: Ignoring mount for partition: volumio Jan 26 14:55:18 volumio volumio[31634]: info: Ignoring mount for partition: volumio_data Jan 26 14:55:18 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 14:55:18 volumio volumio[31634]: info: Loading plugin "volumio_command_line_client"... Jan 26 14:55:19 volumio volumio[31634]: info: Loading plugin "upnp"... Jan 26 14:55:19 volumio volumio[31634]: info: [1769414119019] Starting Upmpd Daemon Jan 26 14:55:19 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 14:55:19 volumio volumio[31634]: info: Loading plugin "my_music"... Jan 26 14:55:19 volumio volumio[31634]: info: Loading plugin "mpd"... Jan 26 14:55:19 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:19 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:19 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:19 volumio volumio[31634]: info: Loading plugin "upnp_browser"... Jan 26 14:55:21 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:21 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:21 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:21 volumio sudo[31693]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 26 14:55:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:21 volumio go-librespot[31712]: go-librespot daemon starting... Jan 26 14:55:21 volumio go-librespot[31713]: time="2026-01-26T14:55:21+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:21 volumio go-librespot[31713]: time="2026-01-26T14:55:21+07:00" level=debug msg="app state loaded" Jan 26 14:55:21 volumio go-librespot[31713]: time="2026-01-26T14:55:21+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=info msg="zeroconf server listening on port 38511" Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=debug msg="obtained new client token: AABkYAXjRhg5nvOR2wKAF+hzFE3+yaGt3E0sBgIOiPx/jPxM7HZp0W9/NZxWrnp2j+ZtM3cpM9L+rVxeXV+rNQeGd4oJ0wLzlxa0H78EzgADrsJzIDl4A1h6YwjwfHe96ggwGOu1CO0ZCl4RoqP8f1j2VmYE1pA5Kuw9akybM14UqSGMVCUS+ADeUWbNmyMYjnGk75Nmo96xnXz0T7FMufxq7JmWVs5CKAWfn+PY85HIu1rncQqPP53AzwI=" Jan 26 14:55:22 volumio volumio[31634]: info: Starting UPNP Browser Jan 26 14:55:22 volumio volumio[31634]: info: Loading plugin "alarm-clock"... Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:22 volumio go-librespot[31713]: time="2026-01-26T14:55:22+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:22 volumio volumio[31634]: info: Loading plugin "airplay_emulation"... Jan 26 14:55:22 volumio volumio[31634]: info: Starting Shairport Sync Jan 26 14:55:22 volumio volumio[31634]: info: Loading plugin "last_100"... Jan 26 14:55:23 volumio volumio[31634]: info: Loading plugin "webradio"... Jan 26 14:55:23 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:23 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:23 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:23 volumio volumio[31634]: info: Loading plugin "i2s_dacs"... Jan 26 14:55:23 volumio volumio[31634]: info: Loading plugin "volumiodiscovery"... Jan 26 14:55:23 volumio volumio[31634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 14:55:23 volumio volumio[31634]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:55:23 volumio volumio[31634]: *** WARNING *** For more information see Jan 26 14:55:23 volumio volumio[31634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 14:55:23 volumio volumio[31634]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:55:23 volumio volumio[31634]: *** WARNING *** For more information see Jan 26 14:55:23 volumio node[31634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 14:55:23 volumio node[31634]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:55:23 volumio node[31634]: *** WARNING *** For more information see Jan 26 14:55:23 volumio node[31634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 14:55:23 volumio node[31634]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:55:23 volumio node[31634]: *** WARNING *** For more information see Jan 26 14:55:23 volumio volumio[31634]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 14:55:23 volumio volumio[31634]: info: Discovery: Started advertising with name: Volumio Jan 26 14:55:23 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 14:55:23 volumio volumio[31634]: info: Loading plugin "spop"... Jan 26 14:55:25 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:25 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:25 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:25 volumio volumio[31634]: info: Loading plugin "ytcr"... Jan 26 14:55:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 26 14:55:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:26 volumio go-librespot[31722]: go-librespot daemon starting... Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=debug msg="app state loaded" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=info msg="zeroconf server listening on port 38847" Jan 26 14:55:26 volumio go-librespot[31723]: time="2026-01-26T14:55:26+07:00" level=debug msg="obtained new client token: AABu4b8NcyZ5jnhNF0KCI25Xd9TE/PMiggxa0tolUvrzyaqHmgfcABvNYPWgSdeHkZkCYZ+B6li4WTnc6cg3GDC3952H92WV7uHRMElygVq5pn6XVrdchwvqdIbBfxOQrLOvPieZ7RDSyW6M4Cg2zhsURJ/CmF7hNFVU9dr13hn5mwdPC7sTRTb41vhsi55FvhnpT1aOkTLWHGlZEzZzXbVQeLVyjqy2iVJzUNfgaE1UYN8Aa+0eoGV3fiQ=" Jan 26 14:55:27 volumio go-librespot[31723]: time="2026-01-26T14:55:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:27 volumio go-librespot[31723]: time="2026-01-26T14:55:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:27 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:27 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:27 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:27 volumio volumio[31634]: info: Loading plugin "ytmusic"... Jan 26 14:55:28 volumio volumio-remote-updater[628]: [2026-01-26 14:55:28] [connect] Successful connection Jan 26 14:55:28 volumio volumio[31634]: info: Loading plugin "outputs"... Jan 26 14:55:28 volumio volumio[31634]: info: Loading plugin "albumart"... Jan 26 14:55:28 volumio volumio[31634]: info: Plugin example_plugin is not enabled Jan 26 14:55:28 volumio volumio[31634]: info: Loading plugin "inputs"... Jan 26 14:55:28 volumio volumio[31634]: info: Loading plugin "updater_comm"... Jan 26 14:55:29 volumio volumio[31634]: info: Plugin mpdemulation is not enabled Jan 26 14:55:29 volumio volumio[31634]: info: Loading plugin "rest_api"... Jan 26 14:55:29 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:29 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:29 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:29 volumio volumio[31634]: info: Loading plugin "websocket"... Jan 26 14:55:29 volumio volumio[31634]: info: Starting Socket.io Server version 1.7.4 Jan 26 14:55:29 volumio volumio[31634]: info: Loading plugin "RoonBridge"... Jan 26 14:55:29 volumio volumio[31634]: info: Applying required configuration parameters for plugin RoonBridge Jan 26 14:55:29 volumio volumio[31634]: info: Loading i18n strings for locale en Jan 26 14:55:29 volumio volumio[31634]: Updating browse sources language Jan 26 14:55:29 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:55:29 volumio volumio[31745]: Forking 3 albumart workers Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::initPlayerControls Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 14:55:30 volumio volumio[31634]: Express server listening on port 3000 Jan 26 14:55:30 volumio volumio[31634]: [Metrics] WebUI: 16s 31.92ms Jan 26 14:55:30 volumio volumio[31634]: info: CoreStateMachine::resetVolumioState Jan 26 14:55:30 volumio volumio[31634]: info: CoreStateMachine::getcurrentVolume Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 14:55:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 26 14:55:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:30 volumio go-librespot[31787]: go-librespot daemon starting... Jan 26 14:55:30 volumio sudo[31789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 14:55:30 volumio sudo[31789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:30 volumio sudo[31789]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:30 volumio go-librespot[31793]: time="2026-01-26T14:55:30+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:30 volumio go-librespot[31793]: time="2026-01-26T14:55:30+07:00" level=debug msg="app state loaded" Jan 26 14:55:30 volumio sudo[31792]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 14:55:30 volumio go-librespot[31793]: time="2026-01-26T14:55:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:30 volumio sudo[31792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:30 volumio sudo[31792]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:30 volumio volumio[31634]: info: Volumio Network Manager: Network status updated: 1 Jan 26 14:55:30 volumio volumio[31634]: info: VolumeController:: Volume=100 Mute =false Jan 26 14:55:30 volumio volumio[31634]: info: CoreStateMachine::pushState Jan 26 14:55:30 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::volumioPushState Jan 26 14:55:30 volumio volumio[31634]: info: CoreStateMachine::updateTrackBlock Jan 26 14:55:30 volumio volumio[31634]: info: CorePlayQueue::getTrackBlock Jan 26 14:55:30 volumio volumio[31634]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 14:55:30 volumio volumio-remote-updater[628]: [2026-01-26 14:55:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769414128 101 Jan 26 14:55:31 volumio volumio[31634]: 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 Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:31 volumio volumio[31634]: info: Reloading queue from file Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=info msg="zeroconf server listening on port 37855" Jan 26 14:55:31 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:31 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:31 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:31 volumio volumio[31634]: info: CoreStateMachine::setRepeat null single undefined Jan 26 14:55:31 volumio volumio[31634]: info: CoreStateMachine::pushState Jan 26 14:55:31 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::volumioPushState Jan 26 14:55:31 volumio volumio[31634]: info: CoreStateMachine::setRandom null Jan 26 14:55:31 volumio volumio[31634]: info: CoreStateMachine::pushState Jan 26 14:55:31 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::volumioPushState Jan 26 14:55:31 volumio volumio[31634]: info: Setting Device type: Raspberry PI Jan 26 14:55:31 volumio volumio[31634]: info: Completed loading Core Plugins Jan 26 14:55:31 volumio volumio[31634]: info: Preparing to generate the ALSA configuration file Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="obtained new client token: AABMLufdctw6IDG8ai4r18dpD5rLysan6F3XqbOj1Wxt6jLcclXKI9r7bZdyuim6PN4bhMmEmYER5ZkfSLx+GkRv9a2pCCkiAh0Tw7Qovw6iUquJ/Ad5Fu56ocZ12ZnUxzcGmxJdpo07Vv5CQxDXBH50jP4DC8XmXqOBokNt8i4Xrh7NDsrR6F15B/omzvWLY3q+pPQcQAy/XYJKWMMhKpe59smPg5nVI4NW2ZE/diPjO6aQg74B1n4wx10=" Jan 26 14:55:31 volumio volumio[31634]: info: VolumeController:: Volume=100 Mute =false Jan 26 14:55:31 volumio volumio[31634]: info: CoreStateMachine::pushState Jan 26 14:55:31 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::volumioPushState Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:31 volumio volumio[31634]: info: Asound.conf file unchanged, so no further update is needed Jan 26 14:55:31 volumio volumio[31634]: info: Output device has changed, restarting MPD Jan 26 14:55:31 volumio sudo[31813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 14:55:31 volumio sudo[31813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:31 volumio volumio[31634]: info: Output device has changed, restarting Shairport Sync Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="completed keyexchange" Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=debug msg="completed challenge" Jan 26 14:55:31 volumio sudo[31815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 14:55:31 volumio sudo[31815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:31 volumio sudo[31815]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:31 volumio go-librespot[31793]: time="2026-01-26T14:55:31+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:55:31 volumio volumio[31634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 14:55:31 volumio sudo[31817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 14:55:31 volumio sudo[31817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:31 volumio volumio[31634]: info: ___________ START PLUGINS ___________ Jan 26 14:55:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:31 volumio volumio[31634]: info: ControllerMpd::onStart: Initializing MPD Jan 26 14:55:31 volumio volumio[31634]: info: Creating MPD Configuration file Jan 26 14:55:31 volumio sudo[31813]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:55:31 volumio volumio[31634]: info: [1769414131884] CoreMusicLibrary::Adding element Media Servers Jan 26 14:55:31 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:55:31 volumio sudo[31828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 14:55:31 volumio sudo[31828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:31 volumio sudo[31827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 14:55:31 volumio sudo[31827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:31 volumio sudo[31827]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:31 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 26 14:55:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 14:55:32 volumio volumio[31634]: info: UPNP Browser: Client initialized successfully Jan 26 14:55:31 volumio systemd[1]: mpd.service: Consumed 7.467s CPU time. Jan 26 14:55:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 26 14:55:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 14:55:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 14:55:32 volumio volumio[31634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 14:55:32 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 26 14:55:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 14:55:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 26 14:55:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 14:55:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 14:55:32 volumio volumio[31634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 14:55:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:55:32 volumio volumio[31634]: info: [1769414132210] CoreMusicLibrary::Adding element Last_100 Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:55:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:55:32 volumio volumio[31634]: info: [1769414132240] CoreMusicLibrary::Adding element Webradio Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 14:55:32 volumio volumio[31634]: info: Initializing BBC Radios Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:32 volumio volumio[31634]: info: Creating Spotify config file Jan 26 14:55:32 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:32 volumio sudo[31842]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 14:55:32 volumio sudo[31842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 14:55:32 volumio sudo[31842]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:33 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:33 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:33 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:33 volumio volumio[31755]: Starting albumart workers Jan 26 14:55:33 volumio volumio[31756]: Starting albumart workers Jan 26 14:55:33 volumio volumio[31634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:55:33 volumio volumio[31634]: info: [1769414133675] CoreMusicLibrary::Adding element YouTube Music Jan 26 14:55:33 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:55:33 volumio volumio[31634]: Cannot find translation for source YouTube Music Jan 26 14:55:33 volumio volumio[31634]: info: Volumio Calling Home Jan 26 14:55:33 volumio sudo[31859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 26 14:55:33 volumio sudo[31859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:33 volumio volumio[31757]: Starting albumart workers Jan 26 14:55:34 volumio sudo[31859]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:34 volumio volumio[31634]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Jan 26 14:55:34 volumio volumio[31634]: info: Discovery: Found device Volumio Jan 26 14:55:34 volumio volumio[31634]: info: CoreCommandRouter::volumioGetState Jan 26 14:55:34 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:34 volumio volumio[31634]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Jan 26 14:55:34 volumio volumio[31634]: info: Discovery: Found device Volumio Jan 26 14:55:34 volumio volumio[31634]: info: CoreCommandRouter::volumioGetState Jan 26 14:55:34 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:34 volumio volumio[31634]: info: MPD Permissions set Jan 26 14:55:34 volumio volumio[31634]: info: MPD Permissions set Jan 26 14:55:34 volumio volumio[31634]: info: Upmpdcli Daemon Started Jan 26 14:55:34 volumio volumio[31634]: info: Volumio called home Jan 26 14:55:34 volumio volumio[31634]: info: Spotify config file written Jan 26 14:55:34 volumio sudo[31866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 26 14:55:34 volumio sudo[31866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 26 14:55:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:35 volumio go-librespot[31879]: go-librespot daemon starting... Jan 26 14:55:35 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:35 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:35 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:35 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 26 14:55:35 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 26 14:55:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:35 volumio go-librespot[31881]: go-librespot daemon starting... Jan 26 14:55:35 volumio sudo[31866]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=debug msg="app state loaded" Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:35 volumio volumio[31634]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:55:35 volumio volumio[31634]: info: No need to fix Spotify hosts Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:35 volumio go-librespot[31882]: time="2026-01-26T14:55:35+07:00" level=info msg="zeroconf server listening on port 46821" Jan 26 14:55:36 volumio go-librespot[31882]: time="2026-01-26T14:55:36+07:00" level=debug msg="obtained new client token: AABzXIc6juI5zmVX+vKaL1smRVa5olSQEWt8zyi7x0DwkKeFSEqiVRDw+7j95SHdRkumQ/9qKYj+JMg14IyLXGv/hl1pAntlfGLs6S0NHQNgEmwwsBtbaBSQqj5orGKyZutisjmxL8VzuEaj7XI4DxY5MLiFLSCnQ0tgZ6H7dYf3KoTCEtCelfMEW4mKLTUfzJj11gUhrVWFARIQNfSaXZbH+uuM8SuS0letevNWODbxcT6J41nv1mGg" Jan 26 14:55:36 volumio volumio[31634]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 26 14:55:36 volumio volumio[31634]: SPOTIFY: BQAd2T2dJC1QQCLlaMLk80NOd0HRhzhzrEWPejCeV4-Yi0OQrzrIZoArsRm49eNTJ74xfINVk2USfOelqZ1fqaCiZZF_0s1sv7HP9d5qRFuXlKFzY32srKBO-Tbc5T7yzJHCnOgstwj1IXojmSWR3hb0nm3w3c3hrUXt9yFd_KRGaMkZq_i_rjxeuiwluzp9sHGwy21_J0xikPS-w7n7PP2o0R0noYK_wT3HPeEh2eOa9-LHHRPQ5eg2WnO_KZoyoJTS4or1MNtX2V8r9HjFcnPNGfrxbu1PIGOpWKcCysj0FotLMtCtDQmV Jan 26 14:55:36 volumio volumio[31634]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 26 14:55:36 volumio volumio[31634]: info: New Spotify access token = BQAd2T2dJC1QQCLlaMLk80NOd0HRhzhzrEWPejCeV4-Yi0OQrzrIZoArsRm49eNTJ74xfINVk2USfOelqZ1fqaCiZZF_0s1sv7HP9d5qRFuXlKFzY32srKBO-Tbc5T7yzJHCnOgstwj1IXojmSWR3hb0nm3w3c3hrUXt9yFd_KRGaMkZq_i_rjxeuiwluzp9sHGwy21_J0xikPS-w7n7PP2o0R0noYK_wT3HPeEh2eOa9-LHHRPQ5eg2WnO_KZoyoJTS4or1MNtX2V8r9HjFcnPNGfrxbu1PIGOpWKcCysj0FotLMtCtDQmV Jan 26 14:55:36 volumio volumio[31634]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 26 14:55:36 volumio go-librespot[31882]: time="2026-01-26T14:55:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:36 volumio volumio[31634]: info: Starting Shairport Sync Jan 26 14:55:36 volumio volumio[31634]: info: Starting Shairport Sync Jan 26 14:55:36 volumio volumio[31634]: info: Starting Shairport Sync Jan 26 14:55:36 volumio sudo[31906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 14:55:36 volumio go-librespot[31882]: time="2026-01-26T14:55:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:36 volumio sudo[31906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:36 volumio sudo[31908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 14:55:36 volumio sudo[31908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:36 volumio sudo[31910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 14:55:36 volumio sudo[31910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 26 14:55:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 26 14:55:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 14:55:36 volumio systemd[1]: shairport-sync.service: Consumed 2.362s CPU time. Jan 26 14:55:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 14:55:36 volumio sudo[31910]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:36 volumio sudo[31906]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:36 volumio sudo[31908]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:36 volumio volumio[31634]: info: Shairport-Sync Started Jan 26 14:55:36 volumio volumio[31634]: Error adding Membership: Error: addMembership EINVAL Jan 26 14:55:36 volumio volumio[31634]: info: Shairport-Sync Started Jan 26 14:55:36 volumio volumio[31634]: info: Shairport-Sync Started Jan 26 14:55:36 volumio volumio[31634]: info: CoreCommandRouter::volumioGetState Jan 26 14:55:36 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:37 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:37 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:37 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:37 volumio volumio[31634]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Jan 26 14:55:37 volumio volumio[31634]: info: Spotify Successfully logged in Jan 26 14:55:37 volumio volumio[31634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:55:37 volumio volumio[31634]: info: [1769414137209] CoreMusicLibrary::Adding element Spotify Jan 26 14:55:37 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:55:37 volumio volumio[31634]: Cannot find translation for source YouTube Music Jan 26 14:55:37 volumio volumio[31634]: Cannot find translation for source Spotify Jan 26 14:55:37 volumio volumio[31634]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 26 14:55:38 volumio volumio[31634]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 14:55:38 volumio volumio[31634]: info: VolumeController:: Volume=100 Mute =false Jan 26 14:55:38 volumio volumio[31634]: info: CoreCommandRouter::volumioGetState Jan 26 14:55:38 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:38 volumio volumio[31634]: info: CoreStateMachine::pushState Jan 26 14:55:38 volumio volumio[31634]: info: CorePlayQueue::getTrack 0 Jan 26 14:55:38 volumio volumio[31634]: info: CoreCommandRouter::volumioPushState Jan 26 14:55:38 volumio volumio[31634]: info: go-librespot daemon successfully initialized Jan 26 14:55:39 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:39 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:39 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 26 14:55:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:39 volumio go-librespot[31953]: go-librespot daemon starting... Jan 26 14:55:39 volumio go-librespot[31954]: time="2026-01-26T14:55:39+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:39 volumio go-librespot[31954]: time="2026-01-26T14:55:39+07:00" level=debug msg="app state loaded" Jan 26 14:55:39 volumio go-librespot[31954]: time="2026-01-26T14:55:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:39 volumio mpd[31857]: 2026-01-26T14:55:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 14:55:39 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 26 14:55:39 volumio sudo[31828]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:39 volumio sudo[31817]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=info msg="zeroconf server listening on port 45839" Jan 26 14:55:40 volumio volumio[31634]: error: MPD error: The expression evaluated to a falsy value: Jan 26 14:55:40 volumio volumio[31634]: assert.ok(self.idling) Jan 26 14:55:40 volumio volumio[31634]: error: The expression evaluated to a falsy value: Jan 26 14:55:40 volumio volumio[31634]: assert.ok(self.idling) Jan 26 14:55:40 volumio volumio[31634]: info: MPD running with PID31857 Jan 26 14:55:40 volumio volumio[31634]: ,establishing connection Jan 26 14:55:40 volumio volumio[31634]: error: updateQueue error: null Jan 26 14:55:40 volumio volumio[31634]: info: Completed starting Core Plugins Jan 26 14:55:40 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:40 volumio volumio[31634]: info: ----- MyVolumio plugins startup ---- Jan 26 14:55:40 volumio volumio[31634]: info: ------------------------------------------- Jan 26 14:55:40 volumio volumio[31634]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 14:55:40 volumio volumio[31634]: error: updateQueue error: null Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=debug msg="obtained new client token: AAA12yvutm4qdNSkRa5jb6f6rhd+DCHW2uyuBE5niAFdXe89vrNz3liAMgabiHwIaq6SlrxBMROOJ8ojATKfZ1HWczHNa+pl04PHrpF/Hm18MXccHCx2e13OOnGS2B1nuiy+TBwRgJZlAohpMc53lPOTOmaNsJRwGN+hV1J/jZkHvvsVvuoRx/FXpj9EawCXFYRpUuKPWqS+Bc2PNCgQ1OQMIcZdR0u+VeKBENugu8VO7ZKapB2H8AnolIQ=" Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:40 volumio go-librespot[31954]: time="2026-01-26T14:55:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:41 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:41 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:41 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:41 volumio volumio[31634]: info: Initializing connection to go-librespot Websocket Jan 26 14:55:41 volumio volumio[31634]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:55:43 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:43 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:43 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 26 14:55:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:43 volumio go-librespot[31966]: go-librespot daemon starting... Jan 26 14:55:43 volumio go-librespot[31967]: time="2026-01-26T14:55:43+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:43 volumio go-librespot[31967]: time="2026-01-26T14:55:43+07:00" level=debug msg="app state loaded" Jan 26 14:55:43 volumio go-librespot[31967]: time="2026-01-26T14:55:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=info msg="zeroconf server listening on port 38893" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="obtained new client token: AAA4JaQoMiBjvNzDzLyu4spntpXqk2CS9O1glBps4UIqvSzpU+KNlO0ee8oU8aqZZlENZRAm3fJwD81P4G6bURDES6cOdvTn742G3fNZ2LxlMPtvCTrat/fepoz5nsloUYjo4g+AwODrOClZ1ZOS0xpD7anEwEuv1IJo4P/U+cE+R/75oWKo+9j9AP9be+YvVXoLe5gf3vz2nprpjMoFqLpSMyszNwrDBb+LEEAEfCBnh13cCuQDcyAuzuE=" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="completed keyexchange" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=debug msg="completed challenge" Jan 26 14:55:44 volumio go-librespot[31967]: time="2026-01-26T14:55:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:55:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:45 volumio volumio[31634]: info: Initializing connection to go-librespot Websocket Jan 26 14:55:45 volumio volumio[31634]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:55:45 volumio volumio[31634]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 14:55:45 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:45 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:45 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:47 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:47 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:47 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 26 14:55:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:48 volumio go-librespot[31975]: go-librespot daemon starting... Jan 26 14:55:48 volumio volumio[31634]: info: Initializing connection to go-librespot Websocket Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=debug msg="app state loaded" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:48 volumio volumio[31634]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=info msg="zeroconf server listening on port 45591" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=debug msg="obtained new client token: AAAnb0NbGC5ruPJHTWynMdpw27nmm/g3ZOS9yN1O98P6YoFmSmcp5E/KNFls5zW84YKy+7vJZJZxxzbGDBIyDIvZMzstlhIP52PDOBtGTKxoECzYiE/cgDpp5Mf3w6XlJwxtw4LMPS0WTsJ7+ILOYBXZfQFNK2kEZED6Jc70j27GSdyQrY/IpD5RrO10XJmcrES7/rlhjEFly6TpTf31Fih4sfDWvWRzRpQjmx+i+RuXxveLt2PyBCdV9Xs=" Jan 26 14:55:48 volumio go-librespot[31976]: time="2026-01-26T14:55:48+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Jan 26 14:55:49 volumio go-librespot[31976]: time="2026-01-26T14:55:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 14:55:49 volumio volumio[31634]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 14:55:49 volumio go-librespot[31976]: time="2026-01-26T14:55:49+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:49 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:49 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:49 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:50 volumio volumio[31634]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 14:55:50 volumio volumio[31634]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 14:55:50 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:50 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:50 volumio volumio[31634]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 14:55:50 volumio volumio[31634]: info: MyVolumio login type: Token Jan 26 14:55:50 volumio volumio[31634]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 14:55:50 volumio volumio[31634]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 14:55:51 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:51 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:51 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 26 14:55:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:52 volumio go-librespot[31997]: go-librespot daemon starting... Jan 26 14:55:52 volumio go-librespot[31998]: time="2026-01-26T14:55:52+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:52 volumio go-librespot[31998]: time="2026-01-26T14:55:52+07:00" level=debug msg="app state loaded" Jan 26 14:55:52 volumio go-librespot[31998]: time="2026-01-26T14:55:52+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:52 volumio volumio[31634]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 14:55:52 volumio volumio[31634]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 14:55:52 volumio volumio[31634]: info: Streaming services startup Jan 26 14:55:52 volumio volumio[31634]: info: Starting Streaming Daemon Jan 26 14:55:52 volumio sudo[32006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 14:55:52 volumio sudo[32006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:52 volumio volumio[31634]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 14:55:52 volumio sudo[32006]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:52 volumio volumio[31634]: info: Initializing connection to go-librespot Websocket Jan 26 14:55:52 volumio volumio[31634]: error: Cannot start Volumio Streaming Daemon Jan 26 14:55:52 volumio volumio[31634]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 14:55:52 volumio volumio[31634]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 14:55:52 volumio go-librespot[31998]: time="2026-01-26T14:55:52+07:00" level=debug msg="new websocket client" Jan 26 14:55:52 volumio volumio[31634]: info: Connection to go-librespot Websocket established Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=info msg="zeroconf server listening on port 38955" Jan 26 14:55:53 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:53 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:53 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=debug msg="obtained new client token: AAD/aeE9nS5dF3l+oKokja0HweY3oZKpetphpd/CUIMVaMje6wvmCA6rea2kcS7CizJfTyF8O1fZL7P8Gfj9rELQeaBxOO9ncQCIgx5d04O9k3GtA6aKUeoWlOXn9VDblrOjeGoESpzR/cdORBajv7D4uGpjxPFp8JxFQ2JoyM+tCeryPBROusTf7n63usEkg6T4MA5bzHKErBm7GyB2IW3nRaTQRlzvHB5cYAG9baVx8C8BlsacMoRnu1c=" Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:53 volumio go-librespot[31998]: time="2026-01-26T14:55:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:53 volumio volumio[31634]: info: Connection to go-librespot Websocket closed Jan 26 14:55:53 volumio volumio[31634]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 26 14:55:54 volumio volumio[31634]: info: MyVolumio token set successfully Jan 26 14:55:54 volumio volumio[31634]: info: MYVOLUMIO: Adding device Jan 26 14:55:54 volumio volumio[31634]: info: MYVOLUMIO: Evaluating Server Jan 26 14:55:55 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:55 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:55 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:55 volumio volumio[31634]: info: MyVolumio status changed Jan 26 14:55:55 volumio volumio[31634]: info: Streaming services startup Jan 26 14:55:55 volumio volumio[31634]: info: Starting Streaming Daemon Jan 26 14:55:55 volumio volumio[31634]: info: Removing browser output: myVolumio user plan is not superstar Jan 26 14:55:55 volumio volumio[31634]: info: Removing audio output: Jan 26 14:55:55 volumio volumio[31634]: info: Stoppping Tunnel 1 Jan 26 14:55:55 volumio sudo[32034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 14:55:55 volumio sudo[32034]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:55 volumio sudo[32036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 26 14:55:55 volumio sudo[32036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:55 volumio volumio[31634]: info: Setting Geolocation for MyVolumio to as1 Jan 26 14:55:55 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:55 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:55 volumio volumio[31634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:55:55 volumio sudo[32034]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:55 volumio 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. Jan 26 14:55:55 volumio 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. Jan 26 14:55:55 volumio volumio[31634]: error: Cannot start Volumio Streaming Daemon Jan 26 14:55:55 volumio volumio[31634]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 14:55:55 volumio volumio[31634]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 14:55:55 volumio 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. Jan 26 14:55:55 volumio 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. Jan 26 14:55:55 volumio 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. Jan 26 14:55:55 volumio 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. Jan 26 14:55:55 volumio sudo[32036]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:55 volumio volumio[31634]: info: Remote SSH Stopped Jan 26 14:55:55 volumio volumio[31634]: info: Getting Spotify volume Jan 26 14:55:55 volumio volumio[31634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 14:55:55 volumio volumio[31634]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:55:55 volumio volumio[31634]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 26 14:55:55 volumio volumio[31634]: errno: -111, Jan 26 14:55:55 volumio volumio[31634]: code: 'ECONNREFUSED', Jan 26 14:55:55 volumio volumio[31634]: syscall: 'connect', Jan 26 14:55:55 volumio volumio[31634]: address: '127.0.0.1', Jan 26 14:55:55 volumio volumio[31634]: port: 9879, Jan 26 14:55:55 volumio volumio[31634]: response: undefined Jan 26 14:55:55 volumio volumio[31634]: } Jan 26 14:55:55 volumio volumio[31634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 14:55:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jan 26 14:55:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:55:56 volumio go-librespot[32051]: go-librespot daemon starting... Jan 26 14:55:56 volumio go-librespot[32052]: time="2026-01-26T14:55:56+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:55:56 volumio go-librespot[32052]: time="2026-01-26T14:55:56+07:00" level=debug msg="app state loaded" Jan 26 14:55:56 volumio go-librespot[32052]: time="2026-01-26T14:55:56+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:55:57 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:57 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:57 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=info msg="zeroconf server listening on port 39867" Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=debug msg="obtained new client token: AAAhmOgDvhElm99xa4Y2WdfcBsYiTwW+YK8qejaB7jj2yp9O/tsc2JALqgLRjOFmu2Vv9fTI7d+1/Lhn6kh0PruZ6NP6AUiKT2zukGSboWZjPeatmHV5+YDgb+DMYbcJwTY3RhocQb60YfHZAH5i4qWnAC9wA+tHRLageMLFkWf1LOfXKzYV7xzOfPWksEYOkOtCdR3sKoOKS/EEenGSJrekaqfnJNxYkElG65XhRxcqaZqnM7TlqtcbenA=" Jan 26 14:55:57 volumio sudo[32062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 14:54' Jan 26 14:55:57 volumio sudo[32062]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:55:57 volumio go-librespot[32052]: time="2026-01-26T14:55:57+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:55:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:55:57 volumio sudo[32062]: pam_unix(sudo:session): session closed for user root Jan 26 14:55:58 volumio volumio-remote-updater[628]: [2026-01-26 14:55:57] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Jan 26 14:55:58 volumio volumio-remote-updater[628]: [2026-01-26 14:55:57] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Jan 26 14:55:58 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:55:58 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 26 14:55:58 volumio systemd[1]: volumio.service: Consumed 1min 11.151s CPU time. Jan 26 14:55:58 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 26 14:55:58 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 26 14:55:58 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7115. Jan 26 14:55:58 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 26 14:55:58 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 26 14:55:58 volumio systemd[1]: volumio.service: Consumed 1min 11.151s CPU time. Jan 26 14:55:58 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 26 14:55:58 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 26 14:55:59 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:55:59 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:55:59 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jan 26 14:56:00 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:00 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:00 volumio go-librespot[32090]: go-librespot daemon starting... Jan 26 14:56:00 volumio go-librespot[32091]: time="2026-01-26T14:56:00+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:00 volumio go-librespot[32091]: time="2026-01-26T14:56:00+07:00" level=debug msg="app state loaded" Jan 26 14:56:00 volumio go-librespot[32091]: time="2026-01-26T14:56:00+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:01 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:01 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:01 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=info msg="zeroconf server listening on port 36519" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="obtained new client token: AABLd+p0WrZ3fSEbilee4AlHe3GhDHA7cc8hcsTp5zV7n5luT9hKde3oGF+397J/ydhA+yv8pxdsbs6kgruKAcmxcOOqWiOSIoFMb9TzD64s64bqPgofyR3Z0nQf31JZwSQNlSZlJ+flzV6cLRnn/nQnKXfMcySb9j42veJTtPHJNy4AijoYhTwHBWlPubdqto6hcTgC47BXQgG0J+YlW8fs8SSNXfw9Paxzv10DP6FJ9ZyY4EUyYx5mLZo=" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:01 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:01 volumio volumio[32074]: info: ----- Volumio3 ---- Jan 26 14:56:01 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:01 volumio volumio[32074]: info: ----- System startup ---- Jan 26 14:56:01 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="completed keyexchange" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=debug msg="completed challenge" Jan 26 14:56:01 volumio go-librespot[32091]: time="2026-01-26T14:56:01+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:56:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:03 volumio volumio-remote-updater[628]: [2026-01-26 14:56:02] [connect] Successful connection Jan 26 14:56:03 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:03 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:03 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:03 volumio volumio[32074]: info: MYVOLUMIO Environment detected Jan 26 14:56:03 volumio volumio[32074]: info: Plugin folders cleanup Jan 26 14:56:03 volumio volumio[32074]: info: Scanning into folder /volumio/app/plugins/ Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category audio_interface Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category miscellanea Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category music_service Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category plugins.json Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category system_controller Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category user_interface Jan 26 14:56:03 volumio volumio[32074]: info: Scanning into folder /data/plugins/ Jan 26 14:56:03 volumio volumio[32074]: info: Scanning category music_service Jan 26 14:56:03 volumio volumio[32074]: info: Plugin folders cleanup completed Jan 26 14:56:03 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:03 volumio volumio[32074]: info: ----- Core plugins startup ---- Jan 26 14:56:03 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:03 volumio volumio[32074]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 14:56:03 volumio volumio[32074]: info: Adding plugin upnp to MyMusic Plugins Jan 26 14:56:03 volumio volumio[32074]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 14:56:03 volumio volumio[32074]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 14:56:03 volumio volumio[32074]: info: Loading plugins from folder /data/plugins/ Jan 26 14:56:03 volumio volumio[32074]: info: Loading plugin "system"... Jan 26 14:56:03 volumio volumio[32074]: info: Loading plugin "appearance"... Jan 26 14:56:05 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:05 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:05 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jan 26 14:56:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:05 volumio go-librespot[32110]: go-librespot daemon starting... Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "network"... Jan 26 14:56:05 volumio volumio[32074]: info: Refreshing Cached IP Addresses Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=debug msg="app state loaded" Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:05 volumio sudo[32118]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 14:56:05 volumio sudo[32118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:05 volumio sudo[32121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 14:56:05 volumio sudo[32121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:05 volumio sudo[32118]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "services"... Jan 26 14:56:05 volumio sudo[32121]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "alsa_controller"... Jan 26 14:56:05 volumio sudo[32132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 26 14:56:05 volumio sudo[32132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:05 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "wizard"... Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "networkfs"... Jan 26 14:56:05 volumio volumio[32074]: info: Starting Udev Watcher for removable devices Jan 26 14:56:05 volumio volumio[32074]: info: Ignoring mount for partition: boot Jan 26 14:56:05 volumio volumio[32074]: info: Ignoring mount for partition: volumio Jan 26 14:56:05 volumio volumio[32074]: info: Ignoring mount for partition: volumio_data Jan 26 14:56:05 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "volumio_command_line_client"... Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "upnp"... Jan 26 14:56:05 volumio volumio[32074]: info: [1769414165796] Starting Upmpd Daemon Jan 26 14:56:05 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "my_music"... Jan 26 14:56:05 volumio volumio[32074]: info: Loading plugin "mpd"... Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:05 volumio go-librespot[32111]: time="2026-01-26T14:56:05+07:00" level=info msg="zeroconf server listening on port 36915" Jan 26 14:56:06 volumio go-librespot[32111]: time="2026-01-26T14:56:06+07:00" level=debug msg="obtained new client token: AAB+SfqhLsnWhpDmo4EC3Owbk8/E7b7VN/gSliDgzAqWMKRTFBuxGiMEI66A9ix4N5XE3+pG0b1MjH7eLxFXuVlzxsPK1FdFmJUiDTnIDcXc5qpEVh9RmAiFTaVYNy3LQD6BAWwhkIqvkvQmUNo32hyKzZp+YLSIExJvfC6FZLI54iqvQ9CYAwuCbjjt0HzksKygjmOVw23YhwgXx9hL9amDEGdGUknvPskjEBijdR2e5XH0qLuKEw4G" Jan 26 14:56:06 volumio go-librespot[32111]: time="2026-01-26T14:56:06+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:06 volumio go-librespot[32111]: time="2026-01-26T14:56:06+07:00" level=debug msg="completed keyexchange" Jan 26 14:56:06 volumio go-librespot[32111]: time="2026-01-26T14:56:06+07:00" level=debug msg="completed challenge" Jan 26 14:56:06 volumio volumio[32074]: info: Loading plugin "upnp_browser"... Jan 26 14:56:06 volumio go-librespot[32111]: time="2026-01-26T14:56:06+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:56:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:07 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:07 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:07 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:08 volumio sudo[32132]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:09 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:09 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:09 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:09 volumio volumio[32074]: info: Starting UPNP Browser Jan 26 14:56:09 volumio volumio[32074]: info: Loading plugin "alarm-clock"... Jan 26 14:56:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jan 26 14:56:09 volumio volumio[32074]: info: Loading plugin "airplay_emulation"... Jan 26 14:56:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:09 volumio volumio[32074]: info: Starting Shairport Sync Jan 26 14:56:09 volumio volumio[32074]: info: Loading plugin "last_100"... Jan 26 14:56:09 volumio volumio[32074]: info: Loading plugin "webradio"... Jan 26 14:56:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:09 volumio go-librespot[32152]: go-librespot daemon starting... Jan 26 14:56:09 volumio go-librespot[32153]: time="2026-01-26T14:56:09+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:09 volumio go-librespot[32153]: time="2026-01-26T14:56:09+07:00" level=debug msg="app state loaded" Jan 26 14:56:09 volumio go-librespot[32153]: time="2026-01-26T14:56:09+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:09 volumio volumio[32074]: info: Loading plugin "i2s_dacs"... Jan 26 14:56:10 volumio volumio[32074]: info: Loading plugin "volumiodiscovery"... Jan 26 14:56:10 volumio volumio[32074]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 14:56:10 volumio volumio[32074]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:56:10 volumio volumio[32074]: *** WARNING *** For more information see Jan 26 14:56:10 volumio volumio[32074]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 14:56:10 volumio volumio[32074]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:56:10 volumio volumio[32074]: *** WARNING *** For more information see Jan 26 14:56:10 volumio node[32074]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 14:56:10 volumio node[32074]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:56:10 volumio node[32074]: *** WARNING *** For more information see Jan 26 14:56:10 volumio node[32074]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 14:56:10 volumio node[32074]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 14:56:10 volumio node[32074]: *** WARNING *** For more information see Jan 26 14:56:10 volumio volumio[32074]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 14:56:10 volumio volumio[32074]: info: Discovery: Started advertising with name: Volumio Jan 26 14:56:10 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 14:56:10 volumio volumio[32074]: info: Loading plugin "spop"... Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=info msg="zeroconf server listening on port 37983" Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=debug msg="obtained new client token: AADoN1oZ/O7Aky/WbSn01NYo/d5MCsUW8fMGs2RGcGmuw5o4HIidgrhnhGxCWIpftAfWc6+EqbQS7pEgOn43hSuHoJd0DV5dXPxR2ehGTet+97Bt80Qelfwqup6EleBY5gIHVX2R5zvruPnjXmdUba0TwF1NkGtMJqEbWslxKE8NidP6fkEW4i/m9ywJPCU5UXsjmCvbVeDTsdI1aaqs90tbIbxW9DSfuzY0P1i9ohXPIP0MkNLOOAyUi+I=" Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:10 volumio go-librespot[32153]: time="2026-01-26T14:56:10+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:56:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:11 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:11 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:11 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:12 volumio volumio[32074]: info: Loading plugin "ytcr"... Jan 26 14:56:13 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:13 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:13 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 26 14:56:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:14 volumio go-librespot[32163]: go-librespot daemon starting... Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=debug msg="app state loaded" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=info msg="zeroconf server listening on port 44253" Jan 26 14:56:14 volumio volumio[32074]: info: Loading plugin "ytmusic"... Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=debug msg="obtained new client token: AAAgXrO0JwJlthBgxLN3BJR+XyUx40kJiOIRxWMoQxneT3gnqMuxnp0NGyNUqYEANludrIiD20Amvk3nFPulL+693hfRBp6PsFfTCubSlsozN6oAZC2qDORqseMDvEH0ineZwaOXAzFLT1JkeXvZ6AVXjV7aJoetBlKRV3NcUZHRB/FiCVx9XgmVdPNEKo7Bk+phKKnIbPBHR8na/QK6v3F1vL68ENZh1gxr2tXBiFO4YxTNXzr8CLUSM3U=" Jan 26 14:56:14 volumio go-librespot[32164]: time="2026-01-26T14:56:14+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:15 volumio go-librespot[32164]: time="2026-01-26T14:56:15+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:56:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:15 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:15 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:15 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:15 volumio volumio-remote-updater[628]: [2026-01-26 14:56:15] [connect] Successful connection Jan 26 14:56:15 volumio volumio[32074]: info: Loading plugin "outputs"... Jan 26 14:56:15 volumio volumio[32074]: info: Loading plugin "albumart"... Jan 26 14:56:15 volumio volumio[32074]: info: Plugin example_plugin is not enabled Jan 26 14:56:15 volumio volumio[32074]: info: Loading plugin "inputs"... Jan 26 14:56:15 volumio volumio[32074]: info: Loading plugin "updater_comm"... Jan 26 14:56:16 volumio volumio[32074]: info: Plugin mpdemulation is not enabled Jan 26 14:56:16 volumio volumio[32074]: info: Loading plugin "rest_api"... Jan 26 14:56:16 volumio volumio[32074]: info: Loading plugin "websocket"... Jan 26 14:56:16 volumio volumio[32074]: info: Starting Socket.io Server version 1.7.4 Jan 26 14:56:16 volumio volumio[32074]: info: Loading plugin "RoonBridge"... Jan 26 14:56:16 volumio volumio[32074]: info: Applying required configuration parameters for plugin RoonBridge Jan 26 14:56:16 volumio volumio[32074]: info: Loading i18n strings for locale en Jan 26 14:56:16 volumio volumio[32074]: Updating browse sources language Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::initPlayerControls Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 14:56:16 volumio volumio[32074]: Express server listening on port 3000 Jan 26 14:56:16 volumio volumio[32074]: [Metrics] WebUI: 16s 114.35ms Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::resetVolumioState Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::getcurrentVolume Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 14:56:17 volumio volumio[32185]: Forking 3 albumart workers Jan 26 14:56:17 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:17 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:17 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:17 volumio sudo[32203]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 14:56:17 volumio sudo[32203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:17 volumio sudo[32203]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:17 volumio sudo[32206]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 14:56:17 volumio sudo[32206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:17 volumio sudo[32206]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:17 volumio volumio[32074]: info: Volumio Network Manager: Network status updated: 1 Jan 26 14:56:17 volumio volumio[32074]: info: VolumeController:: Volume=100 Mute =false Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::pushState Jan 26 14:56:17 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::volumioPushState Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::updateTrackBlock Jan 26 14:56:17 volumio volumio[32074]: info: CorePlayQueue::getTrackBlock Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 14:56:17 volumio volumio-remote-updater[628]: [2026-01-26 14:56:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769414175 101 Jan 26 14:56:17 volumio volumio[32074]: 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 Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:17 volumio volumio[32074]: info: Reloading queue from file Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::setRepeat null single undefined Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::pushState Jan 26 14:56:17 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::volumioPushState Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::setRandom null Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::pushState Jan 26 14:56:17 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::volumioPushState Jan 26 14:56:17 volumio volumio[32074]: info: Setting Device type: Raspberry PI Jan 26 14:56:17 volumio volumio[32074]: info: Completed loading Core Plugins Jan 26 14:56:17 volumio volumio[32074]: info: Preparing to generate the ALSA configuration file Jan 26 14:56:17 volumio volumio[32074]: info: VolumeController:: Volume=100 Mute =false Jan 26 14:56:17 volumio volumio[32074]: info: CoreStateMachine::pushState Jan 26 14:56:17 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:17 volumio volumio[32074]: info: CoreCommandRouter::volumioPushState Jan 26 14:56:18 volumio volumio[32074]: info: Asound.conf file unchanged, so no further update is needed Jan 26 14:56:18 volumio volumio[32074]: info: Output device has changed, restarting MPD Jan 26 14:56:18 volumio volumio[32074]: info: Output device has changed, restarting Shairport Sync Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:18 volumio sudo[32247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 14:56:18 volumio sudo[32247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:18 volumio sudo[32249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 14:56:18 volumio sudo[32249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:18 volumio sudo[32249]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:18 volumio sudo[32251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 14:56:18 volumio sudo[32251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jan 26 14:56:18 volumio volumio[32074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 14:56:18 volumio volumio[32074]: info: ___________ START PLUGINS ___________ Jan 26 14:56:18 volumio volumio[32074]: info: ControllerMpd::onStart: Initializing MPD Jan 26 14:56:18 volumio volumio[32074]: info: Creating MPD Configuration file Jan 26 14:56:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:56:18 volumio volumio[32074]: info: [1769414178311] CoreMusicLibrary::Adding element Media Servers Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:56:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:18 volumio sudo[32260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 14:56:18 volumio sudo[32260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:18 volumio go-librespot[32261]: go-librespot daemon starting... Jan 26 14:56:18 volumio sudo[32263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 14:56:18 volumio sudo[32260]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:18 volumio volumio[32074]: info: UPNP Browser: Client initialized successfully Jan 26 14:56:18 volumio sudo[32263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:18 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 26 14:56:18 volumio sudo[32247]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:18 volumio go-librespot[32264]: time="2026-01-26T14:56:18+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:18 volumio go-librespot[32264]: time="2026-01-26T14:56:18+07:00" level=debug msg="app state loaded" Jan 26 14:56:18 volumio go-librespot[32264]: time="2026-01-26T14:56:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:18 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 26 14:56:18 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 14:56:18 volumio systemd[1]: mpd.service: Consumed 7.433s CPU time. Jan 26 14:56:18 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 26 14:56:18 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 14:56:18 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 14:56:18 volumio volumio[32074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:18 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 14:56:18 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 14:56:18 volumio volumio[32074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:56:18 volumio volumio[32074]: info: [1769414178757] CoreMusicLibrary::Adding element Last_100 Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:56:18 volumio volumio[32074]: info: [1769414178777] CoreMusicLibrary::Adding element Webradio Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:56:18 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 14:56:18 volumio volumio[32074]: info: Initializing BBC Radios Jan 26 14:56:19 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 14:56:19 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:19 volumio volumio[32074]: info: Creating Spotify config file Jan 26 14:56:19 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:19 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:19 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:19 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=info msg="zeroconf server listening on port 42907" Jan 26 14:56:19 volumio sudo[32281]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 14:56:19 volumio sudo[32281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 14:56:19 volumio sudo[32281]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=debug msg="obtained new client token: AAAbhF8GdxS1Ur7m5H65O49eJVDpQs2bzZilQhxmKgBM1xgiv/UEQU5RU9jLcEA7SzVjsG+aCAOatswDtwMqDuw2ZoQXhFXjFRhOPTCCA+CtOYm71eKmCIXov0QWel/pvZh6sg1pYAojqQbtZdq1RJ4VxYIrZFHDFc5tfbWtrOVtmV0I5Mbop8n+mraKZ3qEjj25dGnNcu/cnFd5+f0izArjohFMnIMdXCnbJbGZfiUEUi7yWwtnGqnMWI8=" Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:19 volumio go-librespot[32264]: time="2026-01-26T14:56:19+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.1.107:41140->104.199.241.202:4070: read: connection reset by peer" Jan 26 14:56:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:20 volumio volumio[32202]: Starting albumart workers Jan 26 14:56:20 volumio volumio[32074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:56:20 volumio volumio[32074]: info: [1769414180580] CoreMusicLibrary::Adding element YouTube Music Jan 26 14:56:20 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:56:20 volumio volumio[32074]: Cannot find translation for source YouTube Music Jan 26 14:56:20 volumio volumio[32074]: info: Volumio Calling Home Jan 26 14:56:20 volumio sudo[32300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 26 14:56:20 volumio sudo[32300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:20 volumio volumio[32205]: Starting albumart workers Jan 26 14:56:20 volumio volumio[32200]: Starting albumart workers Jan 26 14:56:20 volumio sudo[32300]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:21 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:21 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:21 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:21 volumio volumio[32074]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Jan 26 14:56:21 volumio volumio[32074]: info: Discovery: Found device Volumio Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::volumioGetState Jan 26 14:56:21 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:21 volumio volumio[32074]: info: MPD Permissions set Jan 26 14:56:21 volumio volumio[32074]: info: MPD Permissions set Jan 26 14:56:21 volumio volumio[32074]: info: Upmpdcli Daemon Started Jan 26 14:56:21 volumio volumio[32074]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Jan 26 14:56:21 volumio volumio[32074]: info: Discovery: Found device Volumio Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::volumioGetState Jan 26 14:56:21 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:21 volumio volumio[32074]: info: Volumio called home Jan 26 14:56:21 volumio volumio[32074]: info: Spotify config file written Jan 26 14:56:21 volumio sudo[32306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 26 14:56:21 volumio volumio[32074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 26 14:56:21 volumio sudo[32306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:22 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:22 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 14:56:22 volumio volumio[32074]: info: No need to fix Spotify hosts Jan 26 14:56:22 volumio go-librespot[32315]: go-librespot daemon starting... Jan 26 14:56:22 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:22 volumio sudo[32306]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=debug msg="app state loaded" Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:22 volumio go-librespot[32319]: time="2026-01-26T14:56:22+07:00" level=info msg="zeroconf server listening on port 37229" Jan 26 14:56:23 volumio go-librespot[32319]: time="2026-01-26T14:56:23+07:00" level=debug msg="obtained new client token: AADq12errhYMLvWm/zdonr/4QDe+VcN5qLSTbe1rr0HaJx0U8Db5j13SrI/ta8C9GB0lDhqR5GA11WP/HAarlDGA4kbWEzewVUmK2Q5KvfynmvnB7A53g7+mEPIZfBML/SfhqTNNPykqR30x0WHmbVZDh1NgWG5o1RrVk2E0yqYsoi9W8CGlEQgQF/zVnQ6ixEZYp2txuvAx9U/SU/vwn/aMP54lkkM39y5GA7zFuRpa6RHNJoP7FP/o" Jan 26 14:56:23 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:23 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:23 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:23 volumio volumio[32074]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 26 14:56:23 volumio volumio[32074]: SPOTIFY: BQBcCnmllfcevoO0auAkE6-Ln6Xe2hSuosnfpmi8ycAaQOrhnSsp_6-WOUhakv1-c2rd6ErJov_2mXhNoJsU8p9-rJAMEmztTl3z6wW3tyqDMyUskeI3wRvU__h0ZSWVoIx53Nzah4PpD6VQjS-l_AGZZCy2mcNySxVDfcHRZmSDVtXBx56MRfWQlOYrNBiqEEIOwhPDj5zJgKGjtNkrh7dglk7j1AYCfCkFjggDsNcUZKVcwIjLw4LaFgm6CNR-aRmTRMRar4wapRsxe6v5JVAxcQV3xNY6j8BF-UQq8wmlPGEyCWL79yUH Jan 26 14:56:23 volumio volumio[32074]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 26 14:56:23 volumio go-librespot[32319]: time="2026-01-26T14:56:23+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:23 volumio volumio[32074]: info: New Spotify access token = BQBcCnmllfcevoO0auAkE6-Ln6Xe2hSuosnfpmi8ycAaQOrhnSsp_6-WOUhakv1-c2rd6ErJov_2mXhNoJsU8p9-rJAMEmztTl3z6wW3tyqDMyUskeI3wRvU__h0ZSWVoIx53Nzah4PpD6VQjS-l_AGZZCy2mcNySxVDfcHRZmSDVtXBx56MRfWQlOYrNBiqEEIOwhPDj5zJgKGjtNkrh7dglk7j1AYCfCkFjggDsNcUZKVcwIjLw4LaFgm6CNR-aRmTRMRar4wapRsxe6v5JVAxcQV3xNY6j8BF-UQq8wmlPGEyCWL79yUH Jan 26 14:56:23 volumio volumio[32074]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 26 14:56:23 volumio volumio[32074]: info: Starting Shairport Sync Jan 26 14:56:23 volumio volumio[32074]: info: Starting Shairport Sync Jan 26 14:56:23 volumio volumio[32074]: info: Starting Shairport Sync Jan 26 14:56:23 volumio go-librespot[32319]: time="2026-01-26T14:56:23+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:56:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:23 volumio sudo[32346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 14:56:23 volumio sudo[32346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:23 volumio sudo[32351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 14:56:23 volumio sudo[32351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:23 volumio sudo[32348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 14:56:23 volumio sudo[32348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 26 14:56:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 26 14:56:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 14:56:23 volumio systemd[1]: shairport-sync.service: Consumed 2.498s CPU time. Jan 26 14:56:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 14:56:23 volumio sudo[32351]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:23 volumio sudo[32348]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:23 volumio sudo[32346]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:23 volumio volumio[32074]: info: Shairport-Sync Started Jan 26 14:56:23 volumio volumio[32074]: Error adding Membership: Error: addMembership EINVAL Jan 26 14:56:23 volumio volumio[32074]: info: CoreCommandRouter::volumioGetState Jan 26 14:56:23 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:23 volumio volumio[32074]: info: Shairport-Sync Started Jan 26 14:56:23 volumio volumio[32074]: info: Shairport-Sync Started Jan 26 14:56:24 volumio volumio[32074]: SPOTIFY: User informations: {"country":"PK","display_name":"215mmgea6kss3jxeqsqdquvji","email":"linh.ngotran@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/215mmgea6kss3jxeqsqdquvji"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/215mmgea6kss3jxeqsqdquvji","id":"215mmgea6kss3jxeqsqdquvji","images":[],"product":"premium","type":"user","uri":"spotify:user:215mmgea6kss3jxeqsqdquvji"} Jan 26 14:56:24 volumio volumio[32074]: info: Spotify Successfully logged in Jan 26 14:56:24 volumio volumio[32074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 14:56:24 volumio volumio[32074]: info: [1769414184089] CoreMusicLibrary::Adding element Spotify Jan 26 14:56:24 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 14:56:24 volumio volumio[32074]: Cannot find translation for source YouTube Music Jan 26 14:56:24 volumio volumio[32074]: Cannot find translation for source Spotify Jan 26 14:56:24 volumio volumio[32074]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 26 14:56:24 volumio volumio[32074]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 14:56:25 volumio volumio[32074]: info: VolumeController:: Volume=100 Mute =false Jan 26 14:56:25 volumio volumio[32074]: info: CoreCommandRouter::volumioGetState Jan 26 14:56:25 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:25 volumio volumio[32074]: info: CoreStateMachine::pushState Jan 26 14:56:25 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:25 volumio volumio[32074]: info: CoreCommandRouter::volumioPushState Jan 26 14:56:25 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:25 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:25 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:26 volumio volumio[32074]: info: go-librespot daemon successfully initialized Jan 26 14:56:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jan 26 14:56:26 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:26 volumio mpd[32297]: 2026-01-26T14:56:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 14:56:26 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:26 volumio go-librespot[32385]: go-librespot daemon starting... Jan 26 14:56:26 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 26 14:56:26 volumio sudo[32263]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:26 volumio sudo[32251]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:26 volumio go-librespot[32388]: time="2026-01-26T14:56:26+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:26 volumio go-librespot[32388]: time="2026-01-26T14:56:26+07:00" level=debug msg="app state loaded" Jan 26 14:56:26 volumio go-librespot[32388]: time="2026-01-26T14:56:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:26 volumio volumio[32074]: error: MPD error: The expression evaluated to a falsy value: Jan 26 14:56:26 volumio volumio[32074]: assert.ok(self.idling) Jan 26 14:56:26 volumio volumio[32074]: error: The expression evaluated to a falsy value: Jan 26 14:56:26 volumio volumio[32074]: assert.ok(self.idling) Jan 26 14:56:26 volumio volumio[32074]: info: Completed starting Core Plugins Jan 26 14:56:26 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:26 volumio volumio[32074]: info: ----- MyVolumio plugins startup ---- Jan 26 14:56:26 volumio volumio[32074]: info: ------------------------------------------- Jan 26 14:56:26 volumio volumio[32074]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 14:56:26 volumio volumio[32074]: error: updateQueue error: null Jan 26 14:56:27 volumio volumio[32074]: info: MPD running with PID32297 Jan 26 14:56:27 volumio volumio[32074]: ,establishing connection Jan 26 14:56:27 volumio volumio[32074]: error: updateQueue error: null Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:27 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:27 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:27 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=info msg="zeroconf server listening on port 35645" Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=debug msg="obtained new client token: AABQ7i3B/JQC8t+ySFAYpwMhcC25UCbvFfXBc6/k8dm/b95nUfgztXbaS2W0HdjNVVRgxh+lco6u1RVa8FtG3J1Bga6k897BRTT9J7xznnyv6FY4e/bqQpkJnAdf9pDqLsXeyJU18XFffZYv6IwDqxLrm/45ABl3jxDByPuZ7ykUIZ+bxqC8ZD/syAjyll6r/aepXEUPUuWpTx/rFm6RN5+tcXO84tEUyPS/ogOlbEX6X9xuSsjXxmJiQIg=" Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:27 volumio go-librespot[32388]: time="2026-01-26T14:56:27+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:56:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:29 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:29 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:29 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:29 volumio volumio[32074]: info: Initializing connection to go-librespot Websocket Jan 26 14:56:29 volumio volumio[32074]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:56:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jan 26 14:56:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:30 volumio go-librespot[32397]: go-librespot daemon starting... Jan 26 14:56:30 volumio go-librespot[32398]: time="2026-01-26T14:56:30+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:30 volumio go-librespot[32398]: time="2026-01-26T14:56:30+07:00" level=debug msg="app state loaded" Jan 26 14:56:30 volumio go-librespot[32398]: time="2026-01-26T14:56:30+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:31 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:31 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:31 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=info msg="zeroconf server listening on port 42135" Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=debug msg="obtained new client token: AACcK7BQJlNhdsDwnKdtrLfKQzYpQ3D3l5kYqCwcKVYn5rbWrg5rf8s096HCRwvBnWD47e2kjkaANiiaQuusBnrg+MZjqhO7uH2VP65RSfFtAoRy+Zhfd9xEI3jH5+K1MqEB+1eS705l+Mqvqwm1/vLniQviXxKc2e7h9zYTV6+Zoc/5aJWZkKo+D9oRUfnWd22sq9Z7AIbfN+CurnQ/gShVk6Rp21U+Un+vHn6/46zqI/KcvVkqmN24Xc0=" Jan 26 14:56:31 volumio volumio[32074]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:31 volumio go-librespot[32398]: time="2026-01-26T14:56:31+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 26 14:56:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:32 volumio volumio[32074]: info: Initializing connection to go-librespot Websocket Jan 26 14:56:32 volumio volumio[32074]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 26 14:56:33 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:33 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:33 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jan 26 14:56:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:35 volumio go-librespot[32405]: go-librespot daemon starting... Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=debug msg="app state loaded" Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:35 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:35 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:35 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:35 volumio volumio[32074]: info: Initializing connection to go-librespot Websocket Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=debug msg="new websocket client" Jan 26 14:56:35 volumio volumio[32074]: info: Connection to go-librespot Websocket established Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=info msg="zeroconf server listening on port 45197" Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 14:56:35 volumio volumio[32074]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 14:56:35 volumio go-librespot[32406]: time="2026-01-26T14:56:35+07:00" level=debug msg="obtained new client token: AABnPOt8RgKfSkcxe4mFdrKgy0/9O9vEaJBnWmpaXwdD46mLvyKcCsCZQ4Bh2zk5sfTzeu+pDvCaDKXzl+OAYYj1AClHq6dhK/KagKJsVgkin9G9MRMaAjFBHx/3DVNi3SbsUCdu4N3oxwjjP5Ao/jV9uuoBjFHQGaM1HduOg6Yn2zDHx9MS6vRclTuousE8zMs6O7iXVoRvVXDrxsOlVk7uYjViOyBo0nY7M3MU6H78lchuwC6DgAqVAQE=" Jan 26 14:56:36 volumio go-librespot[32406]: time="2026-01-26T14:56:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:36 volumio go-librespot[32406]: time="2026-01-26T14:56:36+07:00" level=debug msg="completed keyexchange" Jan 26 14:56:36 volumio go-librespot[32406]: time="2026-01-26T14:56:36+07:00" level=debug msg="completed challenge" Jan 26 14:56:36 volumio go-librespot[32406]: time="2026-01-26T14:56:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:56:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:37 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:37 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:37 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:37 volumio volumio[32074]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 14:56:37 volumio volumio[32074]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 14:56:37 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:37 volumio volumio[32074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 14:56:37 volumio volumio[32074]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 14:56:37 volumio volumio[32074]: info: MyVolumio login type: Token Jan 26 14:56:37 volumio volumio[32074]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 14:56:37 volumio volumio[32074]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 14:56:39 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:39 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:39 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:39 volumio volumio[32074]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 14:56:39 volumio volumio[32074]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 14:56:39 volumio volumio[32074]: info: Streaming services startup Jan 26 14:56:39 volumio volumio[32074]: info: Starting Streaming Daemon Jan 26 14:56:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jan 26 14:56:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:39 volumio sudo[32430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 14:56:39 volumio sudo[32430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 14:56:39 volumio go-librespot[32431]: go-librespot daemon starting... Jan 26 14:56:39 volumio volumio[32074]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 14:56:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 14:56:39 volumio go-librespot[32436]: time="2026-01-26T14:56:39+07:00" level=info msg="running go-librespot 0.4.0" Jan 26 14:56:39 volumio go-librespot[32436]: time="2026-01-26T14:56:39+07:00" level=debug msg="app state loaded" Jan 26 14:56:39 volumio go-librespot[32436]: time="2026-01-26T14:56:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 14:56:39 volumio sudo[32430]: pam_unix(sudo:session): session closed for user root Jan 26 14:56:39 volumio volumio[32074]: info: Getting Spotify volume Jan 26 14:56:39 volumio volumio[32074]: info: Connection to go-librespot Websocket closed Jan 26 14:56:39 volumio volumio[32074]: error: Cannot start Volumio Streaming Daemon Jan 26 14:56:39 volumio volumio[32074]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 14:56:39 volumio volumio[32074]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 14:56:39 volumio volumio[32074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 26 14:56:39 volumio go-librespot[32436]: time="2026-01-26T14:56:39+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 14:56:39 volumio go-librespot[32436]: time="2026-01-26T14:56:39+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 14:56:39 volumio go-librespot[32436]: time="2026-01-26T14:56:39+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 14:56:40 volumio go-librespot[32436]: time="2026-01-26T14:56:40+07:00" level=info msg="zeroconf server listening on port 39637" Jan 26 14:56:40 volumio volumio[32074]: info: CoreCommandRouter::volumioGetState Jan 26 14:56:40 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:40 volumio go-librespot[32436]: time="2026-01-26T14:56:40+07:00" level=debug msg="obtained new client token: AACbdcIMnk7Na5YZZW+ifHjDhQTWTaSJpgfAnuzIB+cSVT3rNIa7GoVdDSm4HILXk1IJVVIvdu7qT7GdUw2AE0VFgwbsfKp8b9NETM5xTCRmYnLVEsK1mpPNtdDj5Uh3Jol9FTgL30es+oHeqPqydYZP5uFfoc1pLvnclgBtH+e5MiWJXlJSeGsK5Cy8n0Pgmt9w9nd96BH0XFlKXNPrGPsCDeVjvzFlBjZBUgmNE5MI7RguMDwHqMsh" Jan 26 14:56:40 volumio volumio[32074]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 26 14:56:40 volumio volumio[32074]: SPOTIFY: SPOTIFY VOLUME undefined Jan 26 14:56:40 volumio volumio[32074]: SPOTIFY: VOLUMIO VOLUME 100 Jan 26 14:56:40 volumio volumio[32074]: info: Aligning Spotify Volume to Volumio Volume Jan 26 14:56:40 volumio volumio[32074]: info: CoreCommandRouter::volumioGetState Jan 26 14:56:40 volumio volumio[32074]: info: CorePlayQueue::getTrack 0 Jan 26 14:56:40 volumio volumio[32074]: info: Setting Spotify Volume from Volumio: 100 Jan 26 14:56:40 volumio go-librespot[32436]: time="2026-01-26T14:56:40+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 26 14:56:40 volumio go-librespot[32436]: time="2026-01-26T14:56:40+07:00" level=debug msg="completed keyexchange" Jan 26 14:56:40 volumio go-librespot[32436]: time="2026-01-26T14:56:40+07:00" level=debug msg="completed challenge" Jan 26 14:56:40 volumio go-librespot[32436]: time="2026-01-26T14:56:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 26 14:56:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 26 14:56:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 26 14:56:40 volumio volumio[32074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 14:56:40 volumio volumio[32074]: Error: socket hang up Jan 26 14:56:40 volumio volumio[32074]: at connResetException (node:internal/errors:720:14) Jan 26 14:56:40 volumio volumio[32074]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 26 14:56:40 volumio volumio[32074]: at Socket.emit (node:events:526:35) Jan 26 14:56:40 volumio volumio[32074]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 26 14:56:40 volumio volumio[32074]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 26 14:56:40 volumio volumio[32074]: code: 'ECONNRESET', Jan 26 14:56:40 volumio volumio[32074]: response: undefined Jan 26 14:56:40 volumio volumio[32074]: } Jan 26 14:56:40 volumio volumio[32074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 14:56:41 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 26 14:56:41 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 26 14:56:41 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 26 14:56:41 volumio sudo[32459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 14:55' Jan 26 14:56:41 volumio sudo[32459]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"