Jan 28 15:08:00 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:00 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:00 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:01 volumio volumio[23017]: info: Loading plugin "network"... Jan 28 15:08:01 volumio volumio[23017]: info: Refreshing Cached IP Addresses Jan 28 15:08:01 volumio sudo[23053]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:08:01 volumio sudo[23053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:01 volumio sudo[23053]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:01 volumio sudo[23055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:08:01 volumio sudo[23055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:01 volumio volumio[23017]: info: Loading plugin "services"... Jan 28 15:08:01 volumio volumio[23017]: info: Loading plugin "alsa_controller"... Jan 28 15:08:01 volumio sudo[23055]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:01 volumio sudo[23065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:08:01 volumio sudo[23065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Jan 28 15:08:01 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:02 volumio go-librespot[23083]: go-librespot daemon starting... Jan 28 15:08:02 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "wizard"... Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "networkfs"... Jan 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+07:00" level=debug msg="app state loaded" Jan 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:02 volumio volumio[23017]: info: Starting Udev Watcher for removable devices Jan 28 15:08:02 volumio volumio[23017]: info: Ignoring mount for partition: boot Jan 28 15:08:02 volumio volumio[23017]: info: Ignoring mount for partition: volumio Jan 28 15:08:02 volumio volumio[23017]: info: Ignoring mount for partition: volumio_data Jan 28 15:08:02 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "volumio_command_line_client"... Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "upnp"... Jan 28 15:08:02 volumio volumio[23017]: info: [1769587682215] Starting Upmpd Daemon Jan 28 15:08:02 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "my_music"... Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "mpd"... Jan 28 15:08:02 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:02 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:02 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+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 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+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 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+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 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+07:00" level=info msg="zeroconf server listening on port 36819" Jan 28 15:08:02 volumio volumio[23017]: info: Loading plugin "upnp_browser"... Jan 28 15:08:02 volumio go-librespot[23087]: time="2026-01-28T15:08:02+07:00" level=debug msg="obtained new client token: AAAXJpjQ4rKZP3jju1wuPABzAjrzgSmIK50xBXd3dX2nKt5YgvxrPAr0uiW0blyioX2zMCLX7jW+Kfh6y8Is+Nj/j1foueUWwsisHdeCKzJqY66h1ZGj/1x8/1QysqAypu4fG0nOdfZqG62/xJqRphA9uqn91Pv0bEm1b7drpgJ4zBZv0Owk0tRQas6Is037de8vM+u2Xj1maM86CTinyqo/0qb6F4RJQoKFQCNk+I7xGCIu2UwWI+RgIQ8=" Jan 28 15:08:03 volumio go-librespot[23087]: time="2026-01-28T15:08:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:03 volumio go-librespot[23087]: time="2026-01-28T15:08:03+07:00" level=debug msg="completed keyexchange" Jan 28 15:08:03 volumio go-librespot[23087]: time="2026-01-28T15:08:03+07:00" level=debug msg="completed challenge" Jan 28 15:08:03 volumio go-librespot[23087]: time="2026-01-28T15:08:03+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 28 15:08:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:04 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:04 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:04 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:04 volumio sudo[23065]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:05 volumio volumio[23017]: info: Starting UPNP Browser Jan 28 15:08:05 volumio volumio[23017]: info: Loading plugin "alarm-clock"... Jan 28 15:08:06 volumio volumio[23017]: info: Loading plugin "airplay_emulation"... Jan 28 15:08:06 volumio volumio[23017]: info: Starting Shairport Sync Jan 28 15:08:06 volumio volumio[23017]: info: Loading plugin "last_100"... Jan 28 15:08:06 volumio volumio[23017]: info: Loading plugin "webradio"... Jan 28 15:08:06 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:06 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:06 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:06 volumio volumio[23017]: info: Loading plugin "i2s_dacs"... Jan 28 15:08:06 volumio volumio[23017]: info: Loading plugin "volumiodiscovery"... Jan 28 15:08:06 volumio volumio[23017]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:06 volumio volumio[23017]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:06 volumio node[23017]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:06 volumio volumio[23017]: *** WARNING *** For more information see Jan 28 15:08:06 volumio volumio[23017]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:06 volumio volumio[23017]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:06 volumio volumio[23017]: *** WARNING *** For more information see Jan 28 15:08:06 volumio node[23017]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:06 volumio node[23017]: *** WARNING *** For more information see Jan 28 15:08:06 volumio node[23017]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:06 volumio node[23017]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:06 volumio node[23017]: *** WARNING *** For more information see Jan 28 15:08:06 volumio volumio[23017]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 15:08:06 volumio volumio[23017]: info: Discovery: Started advertising with name: Volumio Jan 28 15:08:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Jan 28 15:08:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:06 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 15:08:06 volumio volumio[23017]: info: Loading plugin "spop"... Jan 28 15:08:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:06 volumio go-librespot[23096]: go-librespot daemon starting... Jan 28 15:08:06 volumio go-librespot[23097]: time="2026-01-28T15:08:06+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:06 volumio go-librespot[23097]: time="2026-01-28T15:08:06+07:00" level=debug msg="app state loaded" Jan 28 15:08:06 volumio go-librespot[23097]: time="2026-01-28T15:08:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+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 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+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 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+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 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+07:00" level=info msg="zeroconf server listening on port 33907" Jan 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+07:00" level=debug msg="obtained new client token: AAAIFKhJPEGH56jbyFioHPUG9LCV0kPjNstl2e+oftiq+fbNjrTpFFg4/zXOBW4ExBvWM7KZF55Muodn4IZ3XsyhExNZX/lng5v7sr6A0H7Aw97yIvISbikv6R7axW+G5duvl4Vhbw5Gbh+9A/ULHbrVFwbi6u1rYf+GHqvHuas4DWmJyLotUw74jHXGrU9ZmNHiDGR6/XlckhB7WQtU/xrGjfBjqoGKOKmWYjNwcWZhj/sz7TJUvNZ1Txw=" Jan 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:07 volumio go-librespot[23097]: time="2026-01-28T15:08:07+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 28 15:08:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:08 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:08 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:08 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:08 volumio volumio[23017]: info: Loading plugin "ytcr"... Jan 28 15:08:10 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:10 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:10 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Jan 28 15:08:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:10 volumio go-librespot[23104]: go-librespot daemon starting... Jan 28 15:08:10 volumio go-librespot[23105]: time="2026-01-28T15:08:10+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:10 volumio go-librespot[23105]: time="2026-01-28T15:08:10+07:00" level=debug msg="app state loaded" Jan 28 15:08:10 volumio go-librespot[23105]: time="2026-01-28T15:08:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:11 volumio volumio[23017]: info: Loading plugin "ytmusic"... Jan 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+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 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+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 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+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 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+07:00" level=info msg="zeroconf server listening on port 45383" Jan 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+07:00" level=debug msg="obtained new client token: AACi0w8z/Oyx8ccQezXbP1oO+C38f9t0wSPOb6T7oviakZwugw2oGXZY12a+xeWWUdU0BKs3uEINd2yxEtqdPPh37TCDf1V35Dbtd885vsSRx3ZMCZPvOCOsdcXyP7qbHC13lT1n5NT0gzyzm0U/o7dcoxsvrtpdxAUjJtNHpAhrpIL2mdpB0DSIcW/oHzU+A005lj3I2d4iNJCGnGweUGR2gfi1+jXXgNuFqXkB+cetqLXi5R+w7b1pR+0=" Jan 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:11 volumio volumio-remote-updater[628]: [2026-01-28 15:08:11] [connect] Successful connection Jan 28 15:08:11 volumio go-librespot[23105]: time="2026-01-28T15:08:11+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 28 15:08:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "outputs"... Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "albumart"... Jan 28 15:08:12 volumio volumio[23017]: info: Plugin example_plugin is not enabled Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "inputs"... Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "updater_comm"... Jan 28 15:08:12 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:12 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:12 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:12 volumio volumio[23017]: info: Plugin mpdemulation is not enabled Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "rest_api"... Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "websocket"... Jan 28 15:08:12 volumio volumio[23017]: info: Starting Socket.io Server version 1.7.4 Jan 28 15:08:12 volumio volumio[23017]: info: Loading plugin "RoonBridge"... Jan 28 15:08:12 volumio volumio[23017]: info: Applying required configuration parameters for plugin RoonBridge Jan 28 15:08:13 volumio volumio[23017]: info: Loading i18n strings for locale en Jan 28 15:08:13 volumio volumio[23017]: Updating browse sources language Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:13 volumio volumio[23127]: Forking 3 albumart workers Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::initPlayerControls Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:08:13 volumio volumio[23017]: Express server listening on port 3000 Jan 28 15:08:13 volumio volumio[23017]: [Metrics] WebUI: 16s 135.50ms Jan 28 15:08:13 volumio volumio[23017]: info: CoreStateMachine::resetVolumioState Jan 28 15:08:13 volumio volumio[23017]: info: CoreStateMachine::getcurrentVolume Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:08:13 volumio sudo[23169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:08:13 volumio sudo[23169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:13 volumio sudo[23169]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:13 volumio sudo[23171]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:08:13 volumio sudo[23171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:13 volumio sudo[23171]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:13 volumio volumio[23017]: info: Volumio Network Manager: Network status updated: 1 Jan 28 15:08:13 volumio volumio[23017]: info: VolumeController:: Volume=100 Mute =false Jan 28 15:08:13 volumio volumio[23017]: info: CoreStateMachine::pushState Jan 28 15:08:13 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:13 volumio volumio[23017]: info: CoreStateMachine::updateTrackBlock Jan 28 15:08:13 volumio volumio[23017]: info: CorePlayQueue::getTrackBlock Jan 28 15:08:13 volumio volumio[23017]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:08:14 volumio volumio-remote-updater[628]: [2026-01-28 15:08:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769587691 101 Jan 28 15:08:14 volumio volumio[23017]: 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 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: Reloading queue from file Jan 28 15:08:14 volumio volumio[23017]: info: CoreStateMachine::setRepeat null single undefined Jan 28 15:08:14 volumio volumio[23017]: info: CoreStateMachine::pushState Jan 28 15:08:14 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:14 volumio volumio[23017]: info: CoreStateMachine::setRandom null Jan 28 15:08:14 volumio volumio[23017]: info: CoreStateMachine::pushState Jan 28 15:08:14 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:14 volumio volumio[23017]: info: Setting Device type: Raspberry PI Jan 28 15:08:14 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:14 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:14 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:14 volumio volumio[23017]: info: Completed loading Core Plugins Jan 28 15:08:14 volumio volumio[23017]: info: Preparing to generate the ALSA configuration file Jan 28 15:08:14 volumio volumio[23017]: info: VolumeController:: Volume=100 Mute =false Jan 28 15:08:14 volumio volumio[23017]: info: CoreStateMachine::pushState Jan 28 15:08:14 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:14 volumio volumio[23017]: info: Asound.conf file unchanged, so no further update is needed Jan 28 15:08:14 volumio volumio[23017]: info: Output device has changed, restarting MPD Jan 28 15:08:14 volumio volumio[23017]: info: Output device has changed, restarting Shairport Sync Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:14 volumio sudo[23189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 15:08:14 volumio sudo[23189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:14 volumio sudo[23191]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 15:08:14 volumio sudo[23191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:14 volumio sudo[23191]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:14 volumio volumio[23017]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:08:14 volumio volumio[23017]: info: ___________ START PLUGINS ___________ Jan 28 15:08:14 volumio volumio[23017]: info: ControllerMpd::onStart: Initializing MPD Jan 28 15:08:14 volumio volumio[23017]: info: Creating MPD Configuration file Jan 28 15:08:14 volumio sudo[23193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 15:08:14 volumio sudo[23193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:14 volumio sudo[23189]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:14 volumio volumio[23017]: info: [1769587694650] CoreMusicLibrary::Adding element Media Servers Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:14 volumio volumio[23017]: info: UPNP Browser: Client initialized successfully Jan 28 15:08:14 volumio sudo[23201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 15:08:14 volumio sudo[23201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:14 volumio sudo[23204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 15:08:14 volumio sudo[23201]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:14 volumio sudo[23204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:14 volumio volumio[23017]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:08:14 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 15:08:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 15:08:14 volumio systemd[1]: mpd.service: Consumed 7.349s CPU time. Jan 28 15:08:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Jan 28 15:08:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 15:08:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 15:08:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:14 volumio volumio[23017]: info: [1769587694955] CoreMusicLibrary::Adding element Last_100 Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:14 volumio volumio[23017]: info: [1769587694985] CoreMusicLibrary::Adding element Webradio Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:14 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:08:15 volumio volumio[23017]: info: Initializing BBC Radios Jan 28 15:08:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 15:08:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:15 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:15 volumio go-librespot[23221]: go-librespot daemon starting... Jan 28 15:08:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 15:08:15 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 15:08:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 15:08:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 15:08:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 15:08:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 15:08:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+07:00" level=debug msg="app state loaded" Jan 28 15:08:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:15 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:08:15 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:15 volumio volumio[23017]: info: Creating Spotify config file Jan 28 15:08:15 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:15 volumio sudo[23235]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 15:08:15 volumio sudo[23235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:08:15 volumio sudo[23235]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+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 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+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 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+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 28 15:08:15 volumio go-librespot[23222]: time="2026-01-28T15:08:15+07:00" level=info msg="zeroconf server listening on port 33971" Jan 28 15:08:16 volumio go-librespot[23222]: time="2026-01-28T15:08:16+07:00" level=debug msg="obtained new client token: AAAoVU7ZX91PTdWaXuw/onn5XYD08EHog8Ealin4j71D8b8TLglKhfU6sL4taA/a+TnY12qo5Og2y+VFngHE+im4k+VWUNhbYhgw1NNjCyFG12Og1IESLYnlGh3tgdtaZ2TAFpsrffq/c3m7OpbAitKI4Z8x+f6p8yD2XYWGYOWXYXW/vUNa1EhiqnwQLSuzw/95cPCIwXv4grrxF3wpF0/YKg00Djz46TuUBnKmdqkS7HwvgavzNabQ" Jan 28 15:08:16 volumio go-librespot[23222]: time="2026-01-28T15:08:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:16 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:16 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:16 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:16 volumio go-librespot[23222]: time="2026-01-28T15:08:16+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:57034->104.199.241.202:4070: read: connection reset by peer" Jan 28 15:08:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:16 volumio volumio[23137]: Starting albumart workers Jan 28 15:08:16 volumio volumio[23017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:16 volumio volumio[23017]: info: [1769587696770] CoreMusicLibrary::Adding element YouTube Music Jan 28 15:08:16 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:16 volumio volumio[23017]: Cannot find translation for source YouTube Music Jan 28 15:08:16 volumio volumio[23017]: info: Volumio Calling Home Jan 28 15:08:16 volumio sudo[23245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 28 15:08:16 volumio sudo[23245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:16 volumio volumio[23139]: Starting albumart workers Jan 28 15:08:17 volumio volumio[23138]: Starting albumart workers Jan 28 15:08:17 volumio sudo[23245]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:17 volumio volumio[23017]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Jan 28 15:08:17 volumio volumio[23017]: info: Discovery: Found device Volumio Jan 28 15:08:17 volumio volumio[23017]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:17 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:17 volumio volumio[23017]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Jan 28 15:08:17 volumio volumio[23017]: info: Discovery: Found device Volumio Jan 28 15:08:17 volumio volumio[23017]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:17 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:17 volumio volumio[23017]: info: MPD Permissions set Jan 28 15:08:17 volumio volumio[23017]: info: MPD Permissions set Jan 28 15:08:17 volumio volumio[23017]: info: Upmpdcli Daemon Started Jan 28 15:08:17 volumio volumio[23017]: info: Volumio called home Jan 28 15:08:17 volumio volumio[23017]: info: Spotify config file written Jan 28 15:08:17 volumio sudo[23251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 28 15:08:17 volumio sudo[23251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:18 volumio go-librespot[23253]: go-librespot daemon starting... Jan 28 15:08:18 volumio sudo[23251]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08:18+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08:18+07:00" level=debug msg="app state loaded" Jan 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08:18+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:18 volumio volumio[23017]: 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 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:18 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:18 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:18 volumio volumio[23017]: info: No need to fix Spotify hosts Jan 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08:18+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 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08:18+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 28 15:08:18 volumio go-librespot[23254]: time="2026-01-28T15:08:18+07:00" level=info msg="zeroconf server listening on port 34393" Jan 28 15:08:19 volumio go-librespot[23254]: time="2026-01-28T15:08:19+07:00" level=debug msg="obtained new client token: AABrRHn8Zmt/6UINymYDoUBGEP4Gzuwk3WQzNOJwXWiQEY0Yvb41wqFy5GFR3DOkOKa/WqvrdrtpZPBzq1u5PraFuCbYtSVEEEp6UIPyeihYLXmgx17mtushFt2iH6+mm+TNR0nzLkZGXY9snqn/IoRm8wjMIhuSgLtA/PW37YSl5Nw5shC0N9diGJd7Wj4smNxwi/Umu1qw8Cej0shYgya9cqOqbza9OcjlcD7S5Pi6ouhUUPT9G7iu" Jan 28 15:08:19 volumio go-librespot[23254]: time="2026-01-28T15:08:19+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:19 volumio volumio[23017]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 28 15:08:19 volumio volumio[23017]: SPOTIFY: BQBL4FYsZGvgfk7Eg8ev0f3WckWitHGVycJvML287noMaaIT_Pt8zPUsra3ZpEy8E79td4oebc5m10Nv0G8ar7o0qSXy6wm4tt1M9dHjZoSqsuM1hxFzNwAoBu-yLOYa6IeFrOhJnsfLYrhLZxuL36mHVdHpVLhwY7dMA8E79sZPD9hdysbgKLB8AGOL5DSgVIti7IcLmK71nVXsrnyuk7hK-2K0IxQbHoYpQi0JzPplVHdQXooHW9OTmHJPIDWzA5muK39AUXI7A1FlALxbfb3B-qa9Vq5Dbu3hCkmI4h-PiXIHcII0upeI Jan 28 15:08:19 volumio volumio[23017]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 28 15:08:19 volumio volumio[23017]: info: New Spotify access token = BQBL4FYsZGvgfk7Eg8ev0f3WckWitHGVycJvML287noMaaIT_Pt8zPUsra3ZpEy8E79td4oebc5m10Nv0G8ar7o0qSXy6wm4tt1M9dHjZoSqsuM1hxFzNwAoBu-yLOYa6IeFrOhJnsfLYrhLZxuL36mHVdHpVLhwY7dMA8E79sZPD9hdysbgKLB8AGOL5DSgVIti7IcLmK71nVXsrnyuk7hK-2K0IxQbHoYpQi0JzPplVHdQXooHW9OTmHJPIDWzA5muK39AUXI7A1FlALxbfb3B-qa9Vq5Dbu3hCkmI4h-PiXIHcII0upeI Jan 28 15:08:19 volumio volumio[23017]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 28 15:08:19 volumio volumio[23017]: info: Starting Shairport Sync Jan 28 15:08:19 volumio volumio[23017]: info: Starting Shairport Sync Jan 28 15:08:19 volumio volumio[23017]: info: Starting Shairport Sync Jan 28 15:08:19 volumio sudo[23291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:08:19 volumio sudo[23291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:19 volumio sudo[23294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:08:19 volumio sudo[23294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:19 volumio sudo[23295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:08:19 volumio sudo[23295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 15:08:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 15:08:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:08:19 volumio systemd[1]: shairport-sync.service: Consumed 2.349s CPU time. Jan 28 15:08:19 volumio go-librespot[23254]: time="2026-01-28T15:08: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:57040->104.199.241.202:4070: read: connection reset by peer" Jan 28 15:08:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:20 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:08:20 volumio sudo[23291]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:20 volumio sudo[23295]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:20 volumio volumio[23017]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:20 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:20 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 15:08:20 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 15:08:20 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:08:20 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:08:20 volumio sudo[23294]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:20 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:20 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:20 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:20 volumio volumio[23017]: info: Shairport-Sync Started Jan 28 15:08:20 volumio volumio[23017]: Error adding Membership: Error: addMembership EINVAL Jan 28 15:08:20 volumio volumio[23017]: info: Shairport-Sync Started Jan 28 15:08:20 volumio volumio[23017]: info: Shairport-Sync Started Jan 28 15:08:20 volumio volumio[23017]: 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 28 15:08:20 volumio volumio[23017]: info: Spotify Successfully logged in Jan 28 15:08:20 volumio volumio[23017]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:20 volumio volumio[23017]: info: [1769587700443] CoreMusicLibrary::Adding element Spotify Jan 28 15:08:20 volumio volumio[23017]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:20 volumio volumio[23017]: Cannot find translation for source YouTube Music Jan 28 15:08:20 volumio volumio[23017]: Cannot find translation for source Spotify Jan 28 15:08:20 volumio volumio[23017]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 28 15:08:20 volumio volumio[23017]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:08:21 volumio volumio[23017]: info: VolumeController:: Volume=100 Mute =false Jan 28 15:08:21 volumio volumio[23017]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:21 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:21 volumio volumio[23017]: info: CoreStateMachine::pushState Jan 28 15:08:21 volumio volumio[23017]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:21 volumio volumio[23017]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:22 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:22 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:22 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:22 volumio volumio[23017]: info: go-librespot daemon successfully initialized Jan 28 15:08:22 volumio mpd[23242]: 2026-01-28T15:08:22 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 15:08:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Jan 28 15:08:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 15:08:23 volumio sudo[23204]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:23 volumio sudo[23193]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:23 volumio go-librespot[23334]: go-librespot daemon starting... Jan 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+07:00" level=debug msg="app state loaded" Jan 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:23 volumio volumio[23017]: error: MPD error: The expression evaluated to a falsy value: Jan 28 15:08:23 volumio volumio[23017]: assert.ok(self.idling) Jan 28 15:08:23 volumio volumio[23017]: error: The expression evaluated to a falsy value: Jan 28 15:08:23 volumio volumio[23017]: assert.ok(self.idling) Jan 28 15:08:23 volumio volumio[23017]: error: updateQueue error: null Jan 28 15:08:23 volumio volumio[23017]: info: MPD running with PID23242 Jan 28 15:08:23 volumio volumio[23017]: ,establishing connection Jan 28 15:08:23 volumio volumio[23017]: info: Completed starting Core Plugins Jan 28 15:08:23 volumio volumio[23017]: info: ------------------------------------------- Jan 28 15:08:23 volumio volumio[23017]: info: ----- MyVolumio plugins startup ---- Jan 28 15:08:23 volumio volumio[23017]: info: ------------------------------------------- Jan 28 15:08:23 volumio volumio[23017]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 28 15:08:23 volumio volumio[23017]: error: updateQueue error: null Jan 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+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 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+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 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+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 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+07:00" level=info msg="zeroconf server listening on port 42655" Jan 28 15:08:23 volumio go-librespot[23337]: time="2026-01-28T15:08:23+07:00" level=debug msg="obtained new client token: AACegm8+tpccDFPbEgl3iel1sYVUA7t6G2dZVE0rs5wJfvx/JEyfFcM6CvfVHz+KlVblcRBCkhNpx6GcRd6s4gwQQN+VsCle4wByXU2h2of1OUalGirkvi0A8EnSLiMlx29XQjPAou/ICEl07QK4Hu24mJ0L4fd6XC/n8Vrrpqazj7r0+beJix6fB9zidVHpsOZX8/YhWbJNkC0VfGSf0DuuiAsMimqB2P9kn8FthNujDOUvQqzoxHXjdCs=" Jan 28 15:08:24 volumio go-librespot[23337]: time="2026-01-28T15:08:24+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:24 volumio go-librespot[23337]: time="2026-01-28T15:08:24+07:00" level=debug msg="completed keyexchange" Jan 28 15:08:24 volumio go-librespot[23337]: time="2026-01-28T15:08:24+07:00" level=debug msg="completed challenge" Jan 28 15:08:24 volumio go-librespot[23337]: time="2026-01-28T15:08:24+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 28 15:08:24 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:24 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:24 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:25 volumio volumio[23017]: info: Initializing connection to go-librespot Websocket Jan 28 15:08:25 volumio volumio[23017]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 15:08:26 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:26 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:26 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Jan 28 15:08:27 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:27 volumio go-librespot[23345]: go-librespot daemon starting... Jan 28 15:08:27 volumio go-librespot[23346]: time="2026-01-28T15:08:27+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:27 volumio go-librespot[23346]: time="2026-01-28T15:08:27+07:00" level=debug msg="app state loaded" Jan 28 15:08:27 volumio go-librespot[23346]: time="2026-01-28T15:08:27+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+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 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+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 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+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 28 15:08:28 volumio volumio[23017]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+07:00" level=info msg="zeroconf server listening on port 33493" Jan 28 15:08:28 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:28 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:28 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:28 volumio volumio[23017]: info: Initializing connection to go-librespot Websocket Jan 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+07:00" level=debug msg="new websocket client" Jan 28 15:08:28 volumio volumio[23017]: info: Connection to go-librespot Websocket established Jan 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+07:00" level=debug msg="obtained new client token: AACDuDsHN1nYEpzSa+kXMa1DI02D6X5lMKzQCNFDf1HSt8Ag324e7IAM3qCZPGIBrLa1druw0MfuiHM027X6vv2oTy6r6iE/8tWZu0EEKtLuMjO3TwXWrI23iVkr7Q5R1h6weem5hmjVHnOGPAfQHglNb85zE5w+0zMu9KoRODmkRwWiBqSNCFWuVj9H8xPTG1D4ssVwCP0j28sOyyT8T2ow1FONyyNv4g0pr3IWdUVzU0XvKBr1pX8GHNI=" Jan 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:28 volumio go-librespot[23346]: time="2026-01-28T15:08:28+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 28 15:08:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:28 volumio volumio[23017]: info: Connection to go-librespot Websocket closed Jan 28 15:08:30 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:30 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:30 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:31 volumio volumio[23017]: info: Getting Spotify volume Jan 28 15:08:31 volumio volumio[23017]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 15:08:31 volumio volumio[23017]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 15:08:31 volumio volumio[23017]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 28 15:08:31 volumio volumio[23017]: errno: -111, Jan 28 15:08:31 volumio volumio[23017]: code: 'ECONNREFUSED', Jan 28 15:08:31 volumio volumio[23017]: syscall: 'connect', Jan 28 15:08:31 volumio volumio[23017]: address: '127.0.0.1', Jan 28 15:08:31 volumio volumio[23017]: port: 9879, Jan 28 15:08:31 volumio volumio[23017]: response: undefined Jan 28 15:08:31 volumio volumio[23017]: } Jan 28 15:08:31 volumio volumio[23017]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 15:08:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Jan 28 15:08:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:31 volumio go-librespot[23365]: go-librespot daemon starting... Jan 28 15:08:31 volumio go-librespot[23366]: time="2026-01-28T15:08:31+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:31 volumio go-librespot[23366]: time="2026-01-28T15:08:31+07:00" level=debug msg="app state loaded" Jan 28 15:08:31 volumio go-librespot[23366]: time="2026-01-28T15:08:31+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:32 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:32 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:32 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+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 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+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 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+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 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+07:00" level=info msg="zeroconf server listening on port 43573" Jan 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+07:00" level=debug msg="obtained new client token: AACxb1uFglspSs4HWO5LqQayowlr8dwmITP8xluabw2LL7BiFyRhIELIvze2/0K34OY97MYDQhusRxC4RmD1f7q8s+A8n5Vy2GKdLL+rkJ+tlb0h6CxRACoq79BeQckh3EKP2ovHLBN2W0bflLo5a62PwmuyFEIW4oFwu1kVrAodLr17vddMY/vHLGHzlUFSAmDRXkbrgdPh5YQKqTFrW4xe1l/wjJj2EDnLfwsDQIaSRV79e7Jqe3cfets=" Jan 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:32 volumio go-librespot[23366]: time="2026-01-28T15:08:32+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 28 15:08:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:32 volumio sudo[23389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 15:07' Jan 28 15:08:32 volumio sudo[23389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:33 volumio sudo[23389]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:33 volumio volumio-remote-updater[628]: [2026-01-28 15:08:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 28 15:08:33 volumio volumio-remote-updater[628]: [2026-01-28 15:08:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 28 15:08:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 28 15:08:33 volumio systemd[1]: volumio.service: Consumed 52.754s CPU time. Jan 28 15:08:33 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 15:08:33 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 15:08:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10307. Jan 28 15:08:33 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 28 15:08:33 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Jan 28 15:08:33 volumio systemd[1]: volumio.service: Consumed 52.754s CPU time. Jan 28 15:08:33 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Jan 28 15:08:33 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Jan 28 15:08:34 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:34 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:34 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Jan 28 15:08:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:36 volumio go-librespot[23418]: go-librespot daemon starting... Jan 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+07:00" level=debug msg="app state loaded" Jan 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:36 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:36 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:36 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+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 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+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 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+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 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+07:00" level=info msg="zeroconf server listening on port 39065" Jan 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+07:00" level=debug msg="obtained new client token: AADGOPy1TIV1IuYKPzLfdwc+97XjERRt0jh14NWNHbKrqIcu3Eu9FKNaPMX82dmCiHKuZx5aSL0Nsunfq6MhyBoiWzN+VMC5KhN9+2tJ+qc2oH8qxHO0/gD7kAI1sBpDjHhCChCy2q0aoxxDymBWJbyNzPKInLgOtz7Y6wyp8hmWmkm4D03VOvZbPWJzQhU5r/2JGGqUst10jSm0wz1200OL6EOS0Njmep6kBBTvLrPKWNhScMCMW4hm3qU=" Jan 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+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 28 15:08:36 volumio go-librespot[23419]: time="2026-01-28T15:08:36+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Jan 28 15:08:37 volumio go-librespot[23419]: time="2026-01-28T15:08:37+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 28 15:08:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:37 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:08:37 volumio volumio[23401]: info: ----- Volumio3 ---- Jan 28 15:08:37 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:08:37 volumio volumio[23401]: info: ----- System startup ---- Jan 28 15:08:37 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:08:38 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:38 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:38 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:38 volumio volumio-remote-updater[628]: [2026-01-28 15:08:38] [connect] Successful connection Jan 28 15:08:38 volumio volumio[23401]: info: MYVOLUMIO Environment detected Jan 28 15:08:38 volumio volumio[23401]: info: Plugin folders cleanup Jan 28 15:08:38 volumio volumio[23401]: info: Scanning into folder /volumio/app/plugins/ Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category audio_interface Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category miscellanea Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category music_service Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category plugins.json Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category system_controller Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category user_interface Jan 28 15:08:38 volumio volumio[23401]: info: Scanning into folder /data/plugins/ Jan 28 15:08:38 volumio volumio[23401]: info: Scanning category music_service Jan 28 15:08:38 volumio volumio[23401]: info: Plugin folders cleanup completed Jan 28 15:08:38 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:08:38 volumio volumio[23401]: info: ----- Core plugins startup ---- Jan 28 15:08:38 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:08:38 volumio volumio[23401]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 15:08:38 volumio volumio[23401]: info: Adding plugin upnp to MyMusic Plugins Jan 28 15:08:38 volumio volumio[23401]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 15:08:38 volumio volumio[23401]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 15:08:38 volumio volumio[23401]: info: Loading plugins from folder /data/plugins/ Jan 28 15:08:38 volumio volumio[23401]: info: Loading plugin "system"... Jan 28 15:08:38 volumio volumio[23401]: info: Loading plugin "appearance"... Jan 28 15:08:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Jan 28 15:08:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:40 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:40 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:40 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:40 volumio go-librespot[23438]: go-librespot daemon starting... Jan 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08:40+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08:40+07:00" level=debug msg="app state loaded" Jan 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:40 volumio volumio[23401]: info: Loading plugin "network"... Jan 28 15:08:40 volumio volumio[23401]: info: Refreshing Cached IP Addresses Jan 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08: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-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08:40+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 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08:40+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 28 15:08:40 volumio sudo[23448]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:08:40 volumio sudo[23448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:40 volumio go-librespot[23439]: time="2026-01-28T15:08:40+07:00" level=info msg="zeroconf server listening on port 34795" Jan 28 15:08:40 volumio sudo[23448]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:40 volumio sudo[23450]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:08:40 volumio sudo[23450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:40 volumio volumio[23401]: info: Loading plugin "services"... Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "alsa_controller"... Jan 28 15:08:41 volumio sudo[23450]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:41 volumio sudo[23458]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 15:08:41 volumio sudo[23458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:41 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "wizard"... Jan 28 15:08:41 volumio go-librespot[23439]: time="2026-01-28T15:08:41+07:00" level=debug msg="obtained new client token: AAAJmy8h1hnNziKAFQROVdBZ65PHQt3G2YuxGrg+pOMDlfkrQXZ4PXT6l+f5kdVDRFw9eN6tRxFMzRgU7DjLMUollV1EXmsIR+53dKk5SJV/bL/uO/LMTm69DjswcJR8i50iPLN9+zpM3THhnzEU3w/U3Y0w1G5R6S2f6NkX0RdJnJg2WjmR2x9iM5uWegGZrTdAotYGL6O0IULic6uPq8Jn1gWv1vseXugIkp9hiXTKV6BM6xVy39zg" Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "networkfs"... Jan 28 15:08:41 volumio volumio[23401]: info: Starting Udev Watcher for removable devices Jan 28 15:08:41 volumio go-librespot[23439]: time="2026-01-28T15:08:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:41 volumio volumio[23401]: info: Ignoring mount for partition: boot Jan 28 15:08:41 volumio volumio[23401]: info: Ignoring mount for partition: volumio Jan 28 15:08:41 volumio volumio[23401]: info: Ignoring mount for partition: volumio_data Jan 28 15:08:41 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "volumio_command_line_client"... Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "upnp"... Jan 28 15:08:41 volumio volumio[23401]: info: [1769587721330] Starting Upmpd Daemon Jan 28 15:08:41 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "my_music"... Jan 28 15:08:41 volumio volumio[23401]: info: Loading plugin "mpd"... Jan 28 15:08:41 volumio go-librespot[23439]: time="2026-01-28T15:08:41+07:00" level=debug msg="completed keyexchange" Jan 28 15:08:41 volumio go-librespot[23439]: time="2026-01-28T15:08:41+07:00" level=debug msg="completed challenge" Jan 28 15:08:41 volumio go-librespot[23439]: time="2026-01-28T15:08:41+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 28 15:08:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:42 volumio volumio[23401]: info: Loading plugin "upnp_browser"... Jan 28 15:08:42 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:42 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:42 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:43 volumio sudo[23458]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:44 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:44 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:44 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 67. Jan 28 15:08:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:44 volumio go-librespot[23481]: go-librespot daemon starting... Jan 28 15:08:44 volumio go-librespot[23482]: time="2026-01-28T15:08:44+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:44 volumio go-librespot[23482]: time="2026-01-28T15:08:44+07:00" level=debug msg="app state loaded" Jan 28 15:08:44 volumio go-librespot[23482]: time="2026-01-28T15:08:44+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:44 volumio volumio[23401]: info: Starting UPNP Browser Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "alarm-clock"... Jan 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+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 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+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 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+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 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+07:00" level=info msg="zeroconf server listening on port 44361" Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "airplay_emulation"... Jan 28 15:08:45 volumio volumio[23401]: info: Starting Shairport Sync Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "last_100"... Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "webradio"... Jan 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+07:00" level=debug msg="obtained new client token: AADH//K1GrZD20PQE9jwD25I9EusgYb7q1aY/csaXR5uvlXpC2FXTqeLakTGJGAGKEIEXBOwL7gtWyBCoB/Ok0p3+KkZQpV3qs/WN9xg/ozm4pLjF//3aDBXnspvM7asA5TSkL2GE0DHcaGiWkEdyexsMjCi2FbsKKPUqRpRm8T8emWn+ytxPhhCa0p+eV2ccszjul5xVQLv4ipzjsZeuPqPdWN3JSacX0BSTRttE1a72MoG+F7ObZXrEX0=" Jan 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "i2s_dacs"... Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "volumiodiscovery"... Jan 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+07:00" level=debug msg="completed keyexchange" Jan 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+07:00" level=debug msg="completed challenge" Jan 28 15:08:45 volumio volumio[23401]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:45 volumio volumio[23401]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:45 volumio volumio[23401]: *** WARNING *** For more information see Jan 28 15:08:45 volumio volumio[23401]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:45 volumio volumio[23401]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:45 volumio volumio[23401]: *** WARNING *** For more information see Jan 28 15:08:45 volumio node[23401]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:45 volumio node[23401]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:45 volumio node[23401]: *** WARNING *** For more information see Jan 28 15:08:45 volumio node[23401]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 15:08:45 volumio node[23401]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 15:08:45 volumio node[23401]: *** WARNING *** For more information see Jan 28 15:08:45 volumio volumio[23401]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 15:08:45 volumio volumio[23401]: info: Discovery: Started advertising with name: Volumio Jan 28 15:08:45 volumio go-librespot[23482]: time="2026-01-28T15:08:45+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 28 15:08:45 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:45 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:45 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 15:08:45 volumio volumio[23401]: info: Loading plugin "spop"... Jan 28 15:08:46 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:46 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:46 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:47 volumio volumio[23401]: info: Loading plugin "ytcr"... Jan 28 15:08:48 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:48 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:48 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 68. Jan 28 15:08:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:48 volumio go-librespot[23491]: go-librespot daemon starting... Jan 28 15:08:48 volumio go-librespot[23492]: time="2026-01-28T15:08:48+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:48 volumio go-librespot[23492]: time="2026-01-28T15:08:48+07:00" level=debug msg="app state loaded" Jan 28 15:08:48 volumio go-librespot[23492]: time="2026-01-28T15:08:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08:49+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 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08:49+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 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08:49+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 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08:49+07:00" level=info msg="zeroconf server listening on port 38899" Jan 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08:49+07:00" level=debug msg="obtained new client token: AAAnw36FmIwWCXWgy3xfwRXPuP4QlnAbZkghfP4PlsjWJMafQz7Zx1Fzx8O8/1gcdrZmDdGLH1r/Uelvknrde8D7YZX9WYkbyznKKbmaJGoTMfo8cWrV7GRSWrjIMsgOnxrv8JPeV6DHpBVkggPqP5XKY5LQ53TErjTj7UJcfSJl+yTtWhingXLlYB0ZUM5mKngV2Lhc8fOJo7+Ahcc1YStfjXIGB+1J5yze5d85LwOoFD0YPBtks8b77q4=" Jan 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08:49+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:49 volumio go-librespot[23492]: time="2026-01-28T15:08: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 28 15:08:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:50 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:50 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:50 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:50 volumio volumio[23401]: info: Loading plugin "ytmusic"... Jan 28 15:08:50 volumio volumio-remote-updater[628]: [2026-01-28 15:08:50] [connect] Successful connection Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "outputs"... Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "albumart"... Jan 28 15:08:51 volumio volumio[23401]: info: Plugin example_plugin is not enabled Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "inputs"... Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "updater_comm"... Jan 28 15:08:51 volumio volumio[23401]: info: Plugin mpdemulation is not enabled Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "rest_api"... Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "websocket"... Jan 28 15:08:51 volumio volumio[23401]: info: Starting Socket.io Server version 1.7.4 Jan 28 15:08:51 volumio volumio[23401]: info: Loading plugin "RoonBridge"... Jan 28 15:08:52 volumio volumio[23401]: info: Applying required configuration parameters for plugin RoonBridge Jan 28 15:08:52 volumio volumio[23401]: info: Loading i18n strings for locale en Jan 28 15:08:52 volumio volumio[23401]: Updating browse sources language Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:52 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:52 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:52 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:52 volumio volumio[23513]: Forking 3 albumart workers Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::initPlayerControls Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:08:52 volumio volumio[23401]: Express server listening on port 3000 Jan 28 15:08:52 volumio volumio[23401]: [Metrics] WebUI: 16s 194.90ms Jan 28 15:08:52 volumio volumio[23401]: info: CoreStateMachine::resetVolumioState Jan 28 15:08:52 volumio volumio[23401]: info: CoreStateMachine::getcurrentVolume Jan 28 15:08:52 volumio volumio[23401]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:08:52 volumio sudo[23555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 15:08:52 volumio sudo[23555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:52 volumio sudo[23555]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:52 volumio sudo[23557]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 15:08:52 volumio sudo[23557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:52 volumio sudo[23557]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:52 volumio volumio[23401]: info: Volumio Network Manager: Network status updated: 1 Jan 28 15:08:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 69. Jan 28 15:08:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:53 volumio go-librespot[23564]: go-librespot daemon starting... Jan 28 15:08:53 volumio volumio[23401]: info: VolumeController:: Volume=100 Mute =false Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::pushState Jan 28 15:08:53 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::updateTrackBlock Jan 28 15:08:53 volumio volumio[23401]: info: CorePlayQueue::getTrackBlock Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08:53+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08:53+07:00" level=debug msg="app state loaded" Jan 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:53 volumio volumio-remote-updater[628]: [2026-01-28 15:08:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769587730 101 Jan 28 15:08:53 volumio volumio[23401]: 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 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:53 volumio volumio[23401]: info: Reloading queue from file Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::setRepeat null single undefined Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::pushState Jan 28 15:08:53 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::setRandom null Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::pushState Jan 28 15:08:53 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:53 volumio volumio[23401]: info: Setting Device type: Raspberry PI Jan 28 15:08:53 volumio volumio[23401]: info: Completed loading Core Plugins Jan 28 15:08:53 volumio volumio[23401]: info: Preparing to generate the ALSA configuration file Jan 28 15:08:53 volumio volumio[23401]: info: VolumeController:: Volume=100 Mute =false Jan 28 15:08:53 volumio volumio[23401]: info: CoreStateMachine::pushState Jan 28 15:08:53 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::volumioPushState Jan 28 15:08:53 volumio volumio[23401]: info: Asound.conf file unchanged, so no further update is needed Jan 28 15:08:53 volumio volumio[23401]: info: Output device has changed, restarting MPD Jan 28 15:08:53 volumio sudo[23582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 15:08:53 volumio sudo[23582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08:53+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 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08:53+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 28 15:08:53 volumio volumio[23401]: info: Output device has changed, restarting Shairport Sync Jan 28 15:08:53 volumio go-librespot[23565]: time="2026-01-28T15:08:53+07:00" level=info msg="zeroconf server listening on port 38339" Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:53 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:53 volumio sudo[23582]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:53 volumio sudo[23585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 15:08:53 volumio sudo[23585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:53 volumio sudo[23587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 15:08:53 volumio sudo[23585]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:53 volumio sudo[23587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:53 volumio volumio[23401]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:08:53 volumio volumio[23401]: info: ___________ START PLUGINS ___________ Jan 28 15:08:54 volumio volumio[23401]: info: ControllerMpd::onStart: Initializing MPD Jan 28 15:08:54 volumio volumio[23401]: info: Creating MPD Configuration file Jan 28 15:08:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 28 15:08:54 volumio go-librespot[23565]: time="2026-01-28T15:08:54+07:00" level=debug msg="obtained new client token: AADxkRZXEpFaMQ3R5hOGd6fb9/MhtyJyVw9EpfjvYaM1j4lOtE/kULDJFsPLjqfaQIpmXWfqY8//4rP+Ln/J+4d4w7JJx5674TiesNIoPf0TqBGljlnRLO08ATOmWfjSagOKUqoOQ16vx3hglmGIM9defkNG3jLAEEIMe1kng3UAkjqX+U47De7HoUEFKX1pPi0uDz5h1vLNtFhEuizD2r+BN3tq4YrLWSfXgeBSDjTlmbFxePIjcpV/" Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:54 volumio volumio[23401]: info: [1769587734128] CoreMusicLibrary::Adding element Media Servers Jan 28 15:08:54 volumio sudo[23596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 15:08:54 volumio sudo[23596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:54 volumio sudo[23596]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:54 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 15:08:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 15:08:54 volumio systemd[1]: mpd.service: Consumed 7.390s CPU time. Jan 28 15:08:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 15:08:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 15:08:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 15:08:54 volumio sudo[23598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 15:08:54 volumio sudo[23598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:54 volumio volumio[23401]: info: UPNP Browser: Client initialized successfully Jan 28 15:08:54 volumio go-librespot[23565]: time="2026-01-28T15:08:54+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 15:08:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 15:08:54 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:54 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:54 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:54 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 28 15:08:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 28 15:08:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 28 15:08:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 28 15:08:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 28 15:08:54 volumio go-librespot[23565]: time="2026-01-28T15:08:54+07:00" level=debug msg="completed keyexchange" Jan 28 15:08:54 volumio go-librespot[23565]: time="2026-01-28T15:08:54+07:00" level=debug msg="completed challenge" Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 28 15:08:54 volumio volumio[23401]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:08:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 28 15:08:54 volumio go-librespot[23565]: time="2026-01-28T15:08:54+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 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:54 volumio volumio[23401]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:54 volumio volumio[23401]: info: [1769587734596] CoreMusicLibrary::Adding element Last_100 Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:54 volumio volumio[23401]: info: [1769587734614] CoreMusicLibrary::Adding element Webradio Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:08:54 volumio volumio[23401]: info: Initializing BBC Radios Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:54 volumio volumio[23401]: info: Creating Spotify config file Jan 28 15:08:54 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:54 volumio sudo[23608]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 15:08:55 volumio sudo[23608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 28 15:08:55 volumio sudo[23608]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:55 volumio volumio[23524]: Starting albumart workers Jan 28 15:08:55 volumio volumio[23525]: Starting albumart workers Jan 28 15:08:56 volumio volumio[23401]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:56 volumio volumio[23401]: info: [1769587736074] CoreMusicLibrary::Adding element YouTube Music Jan 28 15:08:56 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:56 volumio volumio[23401]: Cannot find translation for source YouTube Music Jan 28 15:08:56 volumio volumio[23401]: info: Volumio Calling Home Jan 28 15:08:56 volumio sudo[23629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jan 28 15:08:56 volumio sudo[23629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:56 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:56 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:56 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:56 volumio volumio[23523]: Starting albumart workers Jan 28 15:08:56 volumio sudo[23629]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:57 volumio volumio[23401]: info: Discovery: adding 921648ed-0557-4d70-9927-1420682efcc7 Jan 28 15:08:57 volumio volumio[23401]: info: Discovery: Found device Volumio Jan 28 15:08:57 volumio volumio[23401]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:57 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:57 volumio volumio[23401]: info: Discovery: this is already registered, 921648ed-0557-4d70-9927-1420682efcc7 Jan 28 15:08:57 volumio volumio[23401]: info: Discovery: Found device Volumio Jan 28 15:08:57 volumio volumio[23401]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:57 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:57 volumio volumio[23401]: info: MPD Permissions set Jan 28 15:08:57 volumio volumio[23401]: info: MPD Permissions set Jan 28 15:08:57 volumio volumio[23401]: info: Upmpdcli Daemon Started Jan 28 15:08:57 volumio volumio[23401]: info: Volumio called home Jan 28 15:08:57 volumio volumio[23401]: info: Spotify config file written Jan 28 15:08:57 volumio sudo[23643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 28 15:08:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 70. Jan 28 15:08:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:57 volumio sudo[23643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:57 volumio go-librespot[23646]: go-librespot daemon starting... Jan 28 15:08:57 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 28 15:08:57 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 28 15:08:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:08:58 volumio go-librespot[23664]: go-librespot daemon starting... Jan 28 15:08:58 volumio sudo[23643]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+07:00" level=debug msg="app state loaded" Jan 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:08:58 volumio volumio[23401]: 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 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:08:58 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:08:58 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 15:08:58 volumio volumio[23401]: info: No need to fix Spotify hosts Jan 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+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 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+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 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+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 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+07:00" level=info msg="zeroconf server listening on port 40129" Jan 28 15:08:58 volumio go-librespot[23665]: time="2026-01-28T15:08:58+07:00" level=debug msg="obtained new client token: AADqVV/U/OXyP+CklZ2+AlaD/hHqvk+pCGwQFwe8mgi0GILDQS+hnf/EI7x/12hzKFXMlCVH2MtyMK8+MYlQ2hVTssZkQrgxA2+PEU4j2Hk77h+qrZquiDPZxqW2qolbggs50KRsxQj6gQI66pc9kVWXJnKBSa+/kiFszc2l2Un4QD7RpFwXoARplHgmenCn1cG2fNPapmdlDBu44j+uZag23m0iWDd35UodzJdyDgKbm6VsOaUWQIQCDfc=" Jan 28 15:08:58 volumio volumio[23401]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 28 15:08:58 volumio volumio[23401]: SPOTIFY: BQBdRBoX9_eGjIadLsBmdNRUei2FMyg9xUtlXcn7GON-s_Ou6WUS8zCPZWvRPtBW2KQl9qmCTSpG-jAKDrwzSwJWiukBRL-wLSnmVu_c7STDcbbKd8w32pRVg_YA-40VWrzRMVYHUrORoimvG7mzU0xjTHhqa1FaUCIEiL8Hzuqt4xtJjFLq_nRHXLRFQzE499qlguPOYnbmU-0D4T15RamdM2xKaY36qnFhYyiVMTl73gbpriuCUgyDrSHTAvQ-5YOXqAmhfWP0VNojzEUuHLoKw5qF-KWmwi9CHxiH1AFc1WhyrSR-8ANm Jan 28 15:08:58 volumio volumio[23401]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 28 15:08:58 volumio volumio[23401]: info: New Spotify access token = BQBdRBoX9_eGjIadLsBmdNRUei2FMyg9xUtlXcn7GON-s_Ou6WUS8zCPZWvRPtBW2KQl9qmCTSpG-jAKDrwzSwJWiukBRL-wLSnmVu_c7STDcbbKd8w32pRVg_YA-40VWrzRMVYHUrORoimvG7mzU0xjTHhqa1FaUCIEiL8Hzuqt4xtJjFLq_nRHXLRFQzE499qlguPOYnbmU-0D4T15RamdM2xKaY36qnFhYyiVMTl73gbpriuCUgyDrSHTAvQ-5YOXqAmhfWP0VNojzEUuHLoKw5qF-KWmwi9CHxiH1AFc1WhyrSR-8ANm Jan 28 15:08:58 volumio volumio[23401]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 28 15:08:59 volumio go-librespot[23665]: time="2026-01-28T15:08:59+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:08:59 volumio go-librespot[23665]: time="2026-01-28T15:08:59+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 28 15:08:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:08:59 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:08:59 volumio volumio[23401]: info: Starting Shairport Sync Jan 28 15:08:59 volumio volumio[23401]: info: Starting Shairport Sync Jan 28 15:08:59 volumio volumio[23401]: info: Starting Shairport Sync Jan 28 15:08:59 volumio sudo[23684]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:08:59 volumio sudo[23684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:59 volumio sudo[23686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:08:59 volumio sudo[23686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:59 volumio sudo[23689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 15:08:59 volumio sudo[23689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:08:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 28 15:08:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 28 15:08:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:08:59 volumio systemd[1]: shairport-sync.service: Consumed 2.230s CPU time. Jan 28 15:08:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 28 15:08:59 volumio sudo[23689]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:59 volumio sudo[23686]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:59 volumio sudo[23684]: pam_unix(sudo:session): session closed for user root Jan 28 15:08:59 volumio volumio[23401]: info: Shairport-Sync Started Jan 28 15:08:59 volumio volumio[23401]: Error adding Membership: Error: addMembership EINVAL Jan 28 15:08:59 volumio volumio[23401]: info: CoreCommandRouter::volumioGetState Jan 28 15:08:59 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:08:59 volumio volumio[23401]: info: Shairport-Sync Started Jan 28 15:08:59 volumio volumio[23401]: info: Shairport-Sync Started Jan 28 15:08:59 volumio volumio[23401]: 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 28 15:08:59 volumio volumio[23401]: info: Spotify Successfully logged in Jan 28 15:08:59 volumio volumio[23401]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 15:08:59 volumio volumio[23401]: info: [1769587739952] CoreMusicLibrary::Adding element Spotify Jan 28 15:08:59 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 15:08:59 volumio volumio[23401]: Cannot find translation for source YouTube Music Jan 28 15:08:59 volumio volumio[23401]: Cannot find translation for source Spotify Jan 28 15:09:00 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:00 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:00 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:00 volumio volumio[23401]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jan 28 15:09:00 volumio volumio[23401]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 15:09:00 volumio volumio[23401]: info: VolumeController:: Volume=100 Mute =false Jan 28 15:09:00 volumio volumio[23401]: info: CoreCommandRouter::volumioGetState Jan 28 15:09:00 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:09:00 volumio volumio[23401]: info: CoreStateMachine::pushState Jan 28 15:09:00 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:09:00 volumio volumio[23401]: info: CoreCommandRouter::volumioPushState Jan 28 15:09:01 volumio volumio[23401]: info: go-librespot daemon successfully initialized Jan 28 15:09:02 volumio mpd[23627]: 2026-01-28T15:09:02 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 15:09:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 28 15:09:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 28 15:09:02 volumio sudo[23587]: pam_unix(sudo:session): session closed for user root Jan 28 15:09:02 volumio sudo[23598]: pam_unix(sudo:session): session closed for user root Jan 28 15:09:02 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:02 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:02 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:02 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:02 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:02 volumio go-librespot[23726]: go-librespot daemon starting... Jan 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+07:00" level=debug msg="app state loaded" Jan 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:09:02 volumio volumio[23401]: error: MPD error: The expression evaluated to a falsy value: Jan 28 15:09:02 volumio volumio[23401]: assert.ok(self.idling) Jan 28 15:09:02 volumio volumio[23401]: error: The expression evaluated to a falsy value: Jan 28 15:09:02 volumio volumio[23401]: assert.ok(self.idling) Jan 28 15:09:02 volumio volumio[23401]: info: MPD running with PID23627 Jan 28 15:09:02 volumio volumio[23401]: ,establishing connection Jan 28 15:09:02 volumio volumio[23401]: error: updateQueue error: null Jan 28 15:09:02 volumio volumio[23401]: info: Completed starting Core Plugins Jan 28 15:09:02 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:09:02 volumio volumio[23401]: info: ----- MyVolumio plugins startup ---- Jan 28 15:09:02 volumio volumio[23401]: info: ------------------------------------------- Jan 28 15:09:02 volumio volumio[23401]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 28 15:09:02 volumio volumio[23401]: error: updateQueue error: null Jan 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+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 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+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 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+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 28 15:09:02 volumio go-librespot[23729]: time="2026-01-28T15:09:02+07:00" level=info msg="zeroconf server listening on port 45247" Jan 28 15:09:03 volumio go-librespot[23729]: time="2026-01-28T15:09:03+07:00" level=debug msg="obtained new client token: AADP2vJ4b5uJUWluX9c7NMPBrTL0E+GS7XWh4h1mI8Q4afLe9iOnxx3v4sd4g4UjEJ0u3mHaKAKMZBav9Zi5ihf9JTQDxo8+uOqo1VmObca1cOizdiUNxpnOWzjGBmu4UijdgI5NxtUDRABaD1GehpxFPDOpWlOZ6v0JLnEitAowBrAhyRzr0tsQSLwSVVcIazkuV5KW4jvBp3+4vIB6+VFGt1vpaL9TYJiQDXpW8dSEdmyaUTOt4fPx" Jan 28 15:09:03 volumio go-librespot[23729]: time="2026-01-28T15:09:03+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:09:03 volumio go-librespot[23729]: time="2026-01-28T15:09:03+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 28 15:09:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:09:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:09:04 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:04 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:04 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:04 volumio volumio[23401]: info: Initializing connection to go-librespot Websocket Jan 28 15:09:04 volumio volumio[23401]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 15:09:06 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:06 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:06 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 28 15:09:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:06 volumio go-librespot[23737]: go-librespot daemon starting... Jan 28 15:09:06 volumio go-librespot[23738]: time="2026-01-28T15:09:06+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:09:06 volumio go-librespot[23738]: time="2026-01-28T15:09:06+07:00" level=debug msg="app state loaded" Jan 28 15:09:06 volumio go-librespot[23738]: time="2026-01-28T15:09:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+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 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+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 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+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 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+07:00" level=info msg="zeroconf server listening on port 34587" Jan 28 15:09:07 volumio volumio[23401]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+07:00" level=debug msg="obtained new client token: AACKq53eWN5aXpZX8fWN2zC0bJGU7UvMuwcJ9f/uO8xEmnd82P1YHHqcd5DEpqIchncD3raSDvtNlGmAtn0pG72yTdeFwAZR2bGeMeZj4Q7myucjS6/6cxp1CwE58gAR3WKG3PoR5F9mMsTYLGemkdXnkixcFmyx7io+tbl2cyyspdILz8hT+pmHpdUChfxmNEg5U/D1VzDZgN0ztSOfUpgLhPZY/nNngqhLYGPXwYBpVZwaHZTEnXzbPcY=" Jan 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:09:07 volumio go-librespot[23738]: time="2026-01-28T15:09:07+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 28 15:09:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:09:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:09:07 volumio volumio[23401]: info: Initializing connection to go-librespot Websocket Jan 28 15:09:07 volumio volumio[23401]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 15:09:08 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:08 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:08 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:10 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:10 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:10 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 28 15:09:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:10 volumio go-librespot[23746]: go-librespot daemon starting... Jan 28 15:09:10 volumio go-librespot[23747]: time="2026-01-28T15:09:10+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:09:10 volumio go-librespot[23747]: time="2026-01-28T15:09:10+07:00" level=debug msg="app state loaded" Jan 28 15:09:10 volumio go-librespot[23747]: time="2026-01-28T15:09:10+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:09:10 volumio volumio[23401]: info: Initializing connection to go-librespot Websocket Jan 28 15:09:10 volumio go-librespot[23747]: time="2026-01-28T15:09:10+07:00" level=debug msg="new websocket client" Jan 28 15:09:10 volumio volumio[23401]: info: Connection to go-librespot Websocket established Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin bluetooth to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin multiroom to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin metavolumio to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin cd_controller to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 28 15:09:11 volumio volumio[23401]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+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 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+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 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+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 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+07:00" level=info msg="zeroconf server listening on port 43843" Jan 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+07:00" level=debug msg="obtained new client token: AAD7GKI0GFnK09CrNiK1NBiqGIVEkPptRNY6Vyd3sdrgLjs/V79hqZJuzB78cuxavoz0ONQ4BUb64hX9qFeJFAnvPkxuG/sn8tLN8gMkA4MnvtNqY1jS6KYO3TIl2dco60kVAw5NgaWk4JbXSUUyWVrdHDEbYa4ogdDeJbcPD9bfDCGLSgFKeFpSHy8hmMqw6str/HGru/UFyDb7/u6Enr9jwlWBUp/4KNZl2r4sJZe2soxEcXQRHCu4d4c=" Jan 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:09:11 volumio go-librespot[23747]: time="2026-01-28T15:09:11+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 28 15:09:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:09:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:09:12 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:12 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:12 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:13 volumio volumio[23401]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 28 15:09:13 volumio volumio[23401]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 28 15:09:13 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:09:13 volumio volumio[23401]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 15:09:13 volumio volumio[23401]: info: Starting MyVolumio Remote Streaming Endpoints Jan 28 15:09:13 volumio volumio[23401]: info: MyVolumio login type: Token Jan 28 15:09:13 volumio volumio[23401]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 28 15:09:13 volumio volumio[23401]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 28 15:09:14 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:14 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:14 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jan 28 15:09:14 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:14 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 15:09:14 volumio go-librespot[23769]: go-librespot daemon starting... Jan 28 15:09:14 volumio go-librespot[23770]: time="2026-01-28T15:09:14+07:00" level=info msg="running go-librespot 0.4.0" Jan 28 15:09:14 volumio go-librespot[23770]: time="2026-01-28T15:09:14+07:00" level=debug msg="app state loaded" Jan 28 15:09:14 volumio go-librespot[23770]: time="2026-01-28T15:09:14+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 15:09:15 volumio volumio[23401]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 28 15:09:15 volumio volumio[23401]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 28 15:09:15 volumio volumio[23401]: info: Streaming services startup Jan 28 15:09:15 volumio volumio[23401]: info: Starting Streaming Daemon Jan 28 15:09:15 volumio sudo[23777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 28 15:09:15 volumio volumio[23401]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 28 15:09:15 volumio sudo[23777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 15:09:15 volumio sudo[23777]: pam_unix(sudo:session): session closed for user root Jan 28 15:09:15 volumio volumio[23401]: info: Getting Spotify volume Jan 28 15:09:15 volumio volumio[23401]: info: Connection to go-librespot Websocket closed Jan 28 15:09:15 volumio volumio[23401]: error: Cannot start Volumio Streaming Daemon Jan 28 15:09:15 volumio volumio[23401]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 28 15:09:15 volumio volumio[23401]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 28 15:09:15 volumio volumio[23401]: 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 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+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 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+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 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+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 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+07:00" level=info msg="zeroconf server listening on port 37801" Jan 28 15:09:15 volumio volumio[23401]: info: CoreCommandRouter::volumioGetState Jan 28 15:09:15 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:09:15 volumio volumio[23401]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 28 15:09:15 volumio volumio[23401]: SPOTIFY: SPOTIFY VOLUME undefined Jan 28 15:09:15 volumio volumio[23401]: SPOTIFY: VOLUMIO VOLUME 100 Jan 28 15:09:15 volumio volumio[23401]: info: Aligning Spotify Volume to Volumio Volume Jan 28 15:09:15 volumio volumio[23401]: info: CoreCommandRouter::volumioGetState Jan 28 15:09:15 volumio volumio[23401]: info: CorePlayQueue::getTrack 0 Jan 28 15:09:15 volumio volumio[23401]: info: Setting Spotify Volume from Volumio: 100 Jan 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+07:00" level=debug msg="obtained new client token: AAAxP23yBbzoUg9S5wXhNZfXLWXkhVEmaoVnLWNu+xKVEGBkfuaOsPQPNFyMk5KBRUYGaZSrShk/mpOeRUJBMJP0o6/imPh4STaCT6nsFGd7ikHiTLn3F+y8IKJ12tPJp98Yx+zT2eCHdkdCZMO2vm8okPiZ57JQCuw0k88x6IhlVVT/tEm4tJk71je3ZWY2Geatsq26ko+ykfSMrwVI7kxMX//Nqd6DxVXkA/bnjXAweveJEMwJWFkKrgs=" Jan 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+07:00" level=debug msg="completed keyexchange" Jan 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+07:00" level=debug msg="completed challenge" Jan 28 15:09:15 volumio go-librespot[23770]: time="2026-01-28T15:09:15+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 28 15:09:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 15:09:15 volumio volumio[23401]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 15:09:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 15:09:16 volumio volumio[23401]: Error: socket hang up Jan 28 15:09:16 volumio volumio[23401]: at connResetException (node:internal/errors:720:14) Jan 28 15:09:16 volumio volumio[23401]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 28 15:09:16 volumio volumio[23401]: at Socket.emit (node:events:526:35) Jan 28 15:09:16 volumio volumio[23401]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 28 15:09:16 volumio volumio[23401]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 28 15:09:16 volumio volumio[23401]: code: 'ECONNRESET', Jan 28 15:09:16 volumio volumio[23401]: response: undefined Jan 28 15:09:16 volumio volumio[23401]: } Jan 28 15:09:16 volumio volumio[23401]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 15:09:16 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Failed to resolve host name Jan 28 15:09:16 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Jan 28 15:09:16 volumio upmpdcli[1383]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 28 15:09:17 volumio sudo[23798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 15:08' Jan 28 15:09:17 volumio sudo[23798]: 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"