Feb 23 19:10:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 23 19:10:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 23 19:10:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 29. Feb 23 19:10:00 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 23 19:10:00 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 23 19:10:00 volumio systemd[1]: volumio.service: Consumed 30.637s CPU time. Feb 23 19:10:00 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 23 19:10:00 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 23 19:10:00 volumio go-librespot[21472]: time="2026-02-23T19:10:00+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:00 volumio go-librespot[21472]: time="2026-02-23T19:10:00+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:00 volumio go-librespot[21472]: time="2026-02-23T19:10:00+01:00" level=debug msg="completed challenge" Feb 23 19:10:00 volumio go-librespot[21472]: time="2026-02-23T19:10:00+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:00 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:00.917Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 23 19:10:01 volumio go-librespot[21472]: time="2026-02-23T19:10:01+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 23 19:10:01 volumio go-librespot[21472]: time="2026-02-23T19:10:01+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:01 volumio go-librespot[21472]: time="2026-02-23T19:10:01+01:00" level=debug msg="completed challenge" Feb 23 19:10:01 volumio go-librespot[21472]: time="2026-02-23T19:10:01+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:02 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:02 volumio volumio[21499]: info: ----- Volumio3 ---- Feb 23 19:10:02 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:02 volumio volumio[21499]: info: ----- System startup ---- Feb 23 19:10:02 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:02 volumio go-librespot[21472]: time="2026-02-23T19:10:02+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 23 19:10:02 volumio go-librespot[21472]: time="2026-02-23T19:10:02+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:02 volumio go-librespot[21472]: time="2026-02-23T19:10:02+01:00" level=debug msg="completed challenge" Feb 23 19:10:02 volumio go-librespot[21472]: time="2026-02-23T19:10:02+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:02 volumio volumio[21499]: info: MYVOLUMIO Environment detected Feb 23 19:10:02 volumio volumio[21499]: info: Plugin folders cleanup Feb 23 19:10:02 volumio volumio[21499]: info: Scanning into folder /volumio/app/plugins/ Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category audio_interface Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category miscellanea Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category music_service Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category plugins.json Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category system_controller Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category user_interface Feb 23 19:10:02 volumio volumio[21499]: info: Scanning into folder /data/plugins/ Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category music_service Feb 23 19:10:02 volumio volumio[21499]: info: Scanning category user_interface Feb 23 19:10:02 volumio volumio[21499]: info: Plugin folders cleanup completed Feb 23 19:10:02 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:02 volumio volumio[21499]: info: ----- Core plugins startup ---- Feb 23 19:10:02 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:02 volumio volumio[21499]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 19:10:02 volumio volumio[21499]: info: Adding plugin upnp to MyMusic Plugins Feb 23 19:10:02 volumio volumio[21499]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 19:10:02 volumio volumio[21499]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 19:10:02 volumio volumio[21499]: info: Loading plugins from folder /data/plugins/ Feb 23 19:10:02 volumio volumio[21499]: info: Loading plugin "system"... Feb 23 19:10:03 volumio volumio[21499]: info: Loading plugin "appearance"... Feb 23 19:10:03 volumio go-librespot[21472]: time="2026-02-23T19:10:03+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 23 19:10:03 volumio go-librespot[21472]: time="2026-02-23T19:10:03+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:03 volumio go-librespot[21472]: time="2026-02-23T19:10:03+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:03 volumio go-librespot[21472]: time="2026-02-23T19:10:03+01:00" level=debug msg="completed challenge" Feb 23 19:10:03 volumio go-librespot[21472]: time="2026-02-23T19:10:03+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:03 volumio go-librespot[21472]: time="2026-02-23T19:10:03+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "network"... Feb 23 19:10:04 volumio volumio[21499]: info: Refreshing Cached IP Addresses Feb 23 19:10:04 volumio sudo[21527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 19:10:04 volumio sudo[21527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "services"... Feb 23 19:10:04 volumio sudo[21527]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "volumio5onboarding"... Feb 23 19:10:04 volumio sudo[21529]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:04 volumio sudo[21529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:04 volumio sudo[21529]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "alsa_controller"... Feb 23 19:10:04 volumio sudo[21536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 19:10:04 volumio sudo[21536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:04 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "wizard"... Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "networkfs"... Feb 23 19:10:04 volumio volumio[21499]: info: Starting Udev Watcher for removable devices Feb 23 19:10:04 volumio volumio[21499]: info: Ignoring mount for partition: boot Feb 23 19:10:04 volumio volumio[21499]: info: Ignoring mount for partition: volumio Feb 23 19:10:04 volumio volumio[21499]: info: Ignoring mount for partition: volumio_data Feb 23 19:10:04 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "volumio_command_line_client"... Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "upnp"... Feb 23 19:10:04 volumio volumio[21499]: info: [1771870204236] Starting Upmpd Daemon Feb 23 19:10:04 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "my_music"... Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "mpd"... Feb 23 19:10:04 volumio volumio[21499]: info: Loading plugin "upnp_browser"... Feb 23 19:10:04 volumio volumio-remote-updater[735]: [2026-02-23 18:10:04] [connect] Successful connection Feb 23 19:10:05 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. Feb 23 19:10:05 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:05 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:05 volumio volumio[21499]: info: Starting UPNP Browser Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "alarm-clock"... Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "airplay_emulation"... Feb 23 19:10:05 volumio volumio[21499]: info: Starting Shairport Sync Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "last_100"... Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "webradio"... Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "i2s_dacs"... Feb 23 19:10:05 volumio volumio[21499]: info: I2S DAC not set, start Auto-detection Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "volumiodiscovery"... Feb 23 19:10:05 volumio volumio[21499]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:05 volumio volumio[21499]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:05 volumio volumio[21499]: *** WARNING *** For more information see Feb 23 19:10:05 volumio volumio[21499]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:05 volumio volumio[21499]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:05 volumio volumio[21499]: *** WARNING *** For more information see Feb 23 19:10:05 volumio node[21499]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:05 volumio node[21499]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:05 volumio node[21499]: *** WARNING *** For more information see Feb 23 19:10:05 volumio node[21499]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:05 volumio node[21499]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:05 volumio node[21499]: *** WARNING *** For more information see Feb 23 19:10:05 volumio volumio[21499]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 19:10:05 volumio volumio[21499]: info: Discovery: Started advertising with name: Volumio Feb 23 19:10:05 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 19:10:05 volumio volumio[21499]: info: Loading plugin "soundcloud"... Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "spop"... Feb 23 19:10:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 23 19:10:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:06 volumio sudo[21536]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:06 volumio go-librespot[21563]: go-librespot daemon starting... Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=debug msg="app state loaded" Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "outputs"... Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "albumart"... Feb 23 19:10:06 volumio volumio[21499]: info: Plugin example_plugin is not enabled Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "inputs"... Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "updater_comm"... Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=info msg="zeroconf server listening on port 44163" Feb 23 19:10:06 volumio volumio[21499]: info: Plugin mpdemulation is not enabled Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "rest_api"... Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "websocket"... Feb 23 19:10:06 volumio volumio[21499]: info: Starting Socket.io Server version 1.7.4 Feb 23 19:10:06 volumio volumio[21499]: info: Loading plugin "touch_display"... Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=debug msg="obtained new client token: AADToJ6UQBDGQNQ2Mr93ynFHQ/ZttbuzDLZQw8S9jMQ+vFDjUYQ7to0WJl76tYZFXQkATMWQ3SAQMRIvlsz+uJn3ZzQa/AUgB3RwYvyJfvAMp80ODG7VNN7Y4LKrOHAap9VIp1BxRv7z/l9x7tk26960Z+IDjtKlRNPrO+fPTTaiGJ35eJhZ8rleFl8kVgAHOqs8y4JEA3I7COJ7PhY+XrU4Omk5QHF0cyxoS6vN9Gmy5r+/dyLLBXY=" Feb 23 19:10:06 volumio go-librespot[21564]: time="2026-02-23T19:10:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:07 volumio volumio[21499]: info: Applying required configuration parameters for plugin touch_display Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.082Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.083Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:07 volumio volumio[21499]: info: Loading i18n strings for locale it Feb 23 19:10:07 volumio volumio[21572]: Forking 3 albumart workers Feb 23 19:10:07 volumio volumio[21499]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 23 19:10:07 volumio volumio[21499]: Updating browse sources language Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::initPlayerControls Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: Express server listening on port 3000 Feb 23 19:10:07 volumio volumio[21499]: [Metrics] WebUI: 5s 744.88ms Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::resetVolumioState Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::getcurrentVolume Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:07 volumio volumio[21499]: info: Volumio Network Manager: Network status updated: 1 Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 23 19:10:07 volumio volumio[21499]: info: VolumeController:: Volume=94 Mute =false Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::pushState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::updateTrackBlock Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrackBlock Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.529Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.529Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.529Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" id= title= Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.530Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" id= title= Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 23 19:10:07 volumio volumio[21499]: info: Received Get System Info Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 19:10:07 volumio volumio[21499]: info: Discovery: Getting this device information Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 23 19:10:07 volumio volumio[21499]: info: Reloading queue from file Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.230 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::setRepeat null single undefined Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::pushState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::setRandom false Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::pushState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.675Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.676Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.677Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" id= title= Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.677Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" id= title= Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.680Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.680Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.681Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" id= title= Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.681Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" id= title= Feb 23 19:10:07 volumio volumio[21499]: info: Setting Device type: Raspberry PI Feb 23 19:10:07 volumio volumio-remote-updater[735]: [2026-02-23 18:10:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771870204 101 Feb 23 19:10:07 volumio volumio[21499]: 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: 4 Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 23 19:10:07 volumio volumio[21499]: info: VolumeController:: Volume=94 Mute =false Feb 23 19:10:07 volumio volumio[21499]: info: CoreStateMachine::pushState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.770Z level=INFO msg="emitting player state changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.771Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" state=STATUS_STOPPED positionMs=0 volume=94 Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.772Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" id= title= Feb 23 19:10:07 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:07.773Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" id= title= Feb 23 19:10:07 volumio volumio[21499]: info: No valid Plugin REST Endpoint Feb 23 19:10:07 volumio qobuz-connect[21560]: 20260223 19:10:07.875 [21560.21560] ERROR SampleApp: api request was not successful Feb 23 19:10:07 volumio qobuz-connect[21560]: Error: Application ID was not specified! Feb 23 19:10:07 volumio qobuz-connect[21560]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:07 volumio qobuz-connect[21560]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:07 volumio qobuz-connect[21560]: -n|--deviceName: Device name Feb 23 19:10:07 volumio qobuz-connect[21560]: -l|--model: Device's model name Feb 23 19:10:07 volumio qobuz-connect[21560]: -s|--serialNumber: Device's serial number Feb 23 19:10:07 volumio qobuz-connect[21560]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:07 volumio qobuz-connect[21560]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:07 volumio qobuz-connect[21560]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:07 volumio qobuz-connect[21560]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:07 volumio qobuz-connect[21560]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:07 volumio qobuz-connect[21560]: -h|--help: Print this help Feb 23 19:10:07 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:07 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.230 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 23 19:10:07 volumio volumio[21499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 19:10:07 volumio volumio[21499]: info: Discovery: adding 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 23 19:10:07 volumio volumio[21499]: info: Discovery: Found device Volumio Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: Discovery: this is already registered, 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 23 19:10:07 volumio volumio[21499]: info: Discovery: Found device Volumio Feb 23 19:10:07 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:07 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:07 volumio volumio[21499]: info: Completed loading Core Plugins Feb 23 19:10:07 volumio volumio[21499]: info: Preparing to generate the ALSA configuration file Feb 23 19:10:08 volumio volumio[21585]: Starting albumart workers Feb 23 19:10:08 volumio volumio[21499]: info: Asound.conf file written Feb 23 19:10:08 volumio sudo[21631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 23 19:10:08 volumio sudo[21631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio volumio[21584]: Starting albumart workers Feb 23 19:10:08 volumio sudo[21631]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: No state is present for card b1 Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: /usr/share/alsa/init/bcm2835:14: control element not found Feb 23 19:10:08 volumio volumio[21499]: No state is present for card b1 Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: No state is present for card Headphones Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: /usr/share/alsa/init/bcm2835:20: control element not found Feb 23 19:10:08 volumio volumio[21499]: No state is present for card Headphones Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: No state is present for card vc4hdmi0 Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 23 19:10:08 volumio volumio[21499]: Hardware is initialized using a generic method Feb 23 19:10:08 volumio volumio[21499]: No state is present for card vc4hdmi0 Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: No state is present for card vc4hdmi1 Feb 23 19:10:08 volumio volumio[21499]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 23 19:10:08 volumio volumio[21499]: Found hardware: "vc4-hdmi" "" "" "" "" Feb 23 19:10:08 volumio volumio[21499]: Hardware is initialized using a generic method Feb 23 19:10:08 volumio volumio[21499]: No state is present for card vc4hdmi1 Feb 23 19:10:08 volumio volumio[21499]: info: Output device has changed, restarting MPD Feb 23 19:10:08 volumio volumio[21499]: info: Output device has changed, restarting Shairport Sync Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:08 volumio sudo[21639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 19:10:08 volumio sudo[21639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio volumio[21499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:08 volumio volumio[21499]: info: ___________ START PLUGINS ___________ Feb 23 19:10:08 volumio sudo[21638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 19:10:08 volumio sudo[21638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 23 19:10:08 volumio sudo[21638]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:08 volumio volumio[21499]: info: ControllerMpd::onStart: Initializing MPD Feb 23 19:10:08 volumio volumio[21499]: info: Creating MPD Configuration file Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=debug msg="completed challenge" Feb 23 19:10:08 volumio volumio[21583]: Starting albumart workers Feb 23 19:10:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 19:10:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 19:10:08 volumio systemd[1]: mpd.service: Consumed 1.976s CPU time. Feb 23 19:10:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 19:10:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 19:10:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:08 volumio sudo[21647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 23 19:10:08 volumio sudo[21647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:08 volumio volumio[21499]: info: [1771870208367] CoreMusicLibrary::Adding element Server multimediali Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 19:10:08 volumio sudo[21650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 19:10:08 volumio sudo[21650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 19:10:08 volumio sudo[21653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 19:10:08 volumio sudo[21650]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:08 volumio sudo[21653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio volumio[21499]: info: UPNP Browser: Client initialized successfully Feb 23 19:10:08 volumio sudo[21647]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 19:10:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 19:10:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 19:10:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 19:10:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 19:10:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 19:10:08 volumio volumio[21499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 19:10:08 volumio volumio[21499]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:08 volumio volumio[21499]: info: [1771870208536] CoreMusicLibrary::Adding element Last_100 Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:08 volumio volumio[21499]: info: [1771870208539] CoreMusicLibrary::Adding element Webradio Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:08 volumio volumio[21499]: info: Initializing BBC Radios Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:08 volumio volumio[21499]: info: [1771870208660] CoreMusicLibrary::Adding element SoundCloud Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:08 volumio volumio[21499]: Cannot find translation for source SoundCloud Feb 23 19:10:08 volumio sudo[21664]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 19:10:08 volumio sudo[21664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:08 volumio sudo[21664]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:08 volumio volumio[21499]: info: Creating Spotify config file Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:08 volumio volumio[21499]: info: Loading i18n strings for locale it Feb 23 19:10:08 volumio volumio[21499]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=debug msg="completed challenge" Feb 23 19:10:08 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:08 volumio volumio[21499]: info: Volumio Calling Home Feb 23 19:10:08 volumio sudo[21696]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 23 19:10:08 volumio sudo[21696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:08 volumio go-librespot[21564]: time="2026-02-23T19:10:08+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:08 volumio sudo[21696]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:09 volumio sudo[21704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 23 19:10:09 volumio sudo[21704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 23 19:10:09 volumio sudo[21703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 19:10:09 volumio sudo[21708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio systemd[1]: Reloading. Feb 23 19:10:09 volumio volumio[21499]: info: touch_display: No backlight interface detected. Feb 23 19:10:09 volumio volumio[21499]: info: MPD Permissions set Feb 23 19:10:09 volumio volumio[21499]: info: MPD Permissions set Feb 23 19:10:09 volumio volumio[21499]: info: Volumio called home Feb 23 19:10:09 volumio volumio[21499]: info: Spotify config file written Feb 23 19:10:09 volumio sudo[21718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 23 19:10:09 volumio sudo[21718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 19:10:09 volumio sudo[21720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21718]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:09 volumio volumio[21499]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:09 volumio volumio[21499]: info: No need to fix Spotify hosts Feb 23 19:10:09 volumio volumio[21499]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 23 19:10:09 volumio go-librespot[21564]: time="2026-02-23T19:10:09+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:09 volumio volumio[21499]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 23 19:10:09 volumio volumio[21499]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 23 19:10:09 volumio volumio[21499]: info: Starting Shairport Sync Feb 23 19:10:09 volumio volumio[21499]: info: Starting Shairport Sync Feb 23 19:10:09 volumio volumio[21499]: info: Starting Shairport Sync Feb 23 19:10:09 volumio sudo[21756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 19:10:09 volumio sudo[21756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 23 19:10:09 volumio sudo[21752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 23 19:10:09 volumio sudo[21754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 19:10:09 volumio sudo[21758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 19:10:09 volumio sudo[21761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:09 volumio sudo[21754]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:09 volumio sudo[21752]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:09 volumio go-librespot[21564]: time="2026-02-23T19:10:09+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:09 volumio go-librespot[21564]: time="2026-02-23T19:10:09+01:00" level=debug msg="completed challenge" Feb 23 19:10:09 volumio volumio[21499]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 23 19:10:09 volumio volumio[21499]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 23 19:10:09 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:09 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:09 volumio go-librespot[21564]: time="2026-02-23T19:10:09+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:10 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:10 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:10 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:10 volumio sudo[21708]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:10 volumio systemd[1]: Reloading. Feb 23 19:10:10 volumio volumio[21499]: info: touch_display: systemctl daemon-reload succeeded. Feb 23 19:10:10 volumio sudo[21768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 23 19:10:10 volumio sudo[21768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:10 volumio go-librespot[21564]: time="2026-02-23T19:10:10+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 23 19:10:10 volumio go-librespot[21564]: time="2026-02-23T19:10:10+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:10 volumio go-librespot[21564]: time="2026-02-23T19:10:10+01:00" level=debug msg="completed challenge" Feb 23 19:10:10 volumio go-librespot[21564]: time="2026-02-23T19:10:10+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:10 volumio volumio[21499]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 23 19:10:10 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:10 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:10 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 19:10:10 volumio volumio[21499]: info: Listing playlists Feb 23 19:10:10 volumio volumio[21499]: info: CoreCommandRouter::volumioGetQueue Feb 23 19:10:10 volumio volumio[21499]: info: CoreStateMachine::getQueue Feb 23 19:10:10 volumio volumio[21499]: info: CorePlayQueue::getQueue Feb 23 19:10:10 volumio volumio[21499]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 19:10:10 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:11 volumio go-librespot[21564]: time="2026-02-23T19:10:11+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 23 19:10:11 volumio mpd[21687]: 2026-02-23T19:10:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 19:10:11 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:11 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:11 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:11 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:11 volumio sudo[21704]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 23 19:10:11 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 19:10:11 volumio sudo[21639]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio sudo[21653]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio go-librespot[21564]: time="2026-02-23T19:10:11+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 23 19:10:11 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 23 19:10:11 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 19:10:11 volumio sudo[21768]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio volumio[21499]: error: MPD error: The expression evaluated to a falsy value: Feb 23 19:10:11 volumio volumio[21499]: assert.ok(self.idling) Feb 23 19:10:11 volumio volumio[21499]: error: The expression evaluated to a falsy value: Feb 23 19:10:11 volumio volumio[21499]: assert.ok(self.idling) Feb 23 19:10:11 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 19:10:11 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 19:10:11 volumio systemd[1]: shairport-sync.service: Consumed 1.773s CPU time. Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: Volumio Kiosk started. Feb 23 19:10:11 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 23 19:10:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:11 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:11 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:11 volumio volumio[21499]: info: Completed starting Core Plugins Feb 23 19:10:11 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:11 volumio volumio[21499]: info: ----- MyVolumio plugins startup ---- Feb 23 19:10:11 volumio volumio[21499]: info: ------------------------------------------- Feb 23 19:10:11 volumio volumio[21499]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 19:10:11 volumio volumio[21499]: info: MPD running with PID21687 Feb 23 19:10:11 volumio volumio[21499]: ,establishing connection Feb 23 19:10:11 volumio volumio[21499]: error: updateQueue error: null Feb 23 19:10:11 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:11 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:11 volumio sudo[21703]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio volumio[21499]: error: updateQueue error: null Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 23 19:10:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:11 volumio go-librespot[21793]: go-librespot daemon starting... Feb 23 19:10:11 volumio sudo[21720]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 19:10:11 volumio sudo[21758]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio sudo[21761]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio volumio[21499]: info: Shairport-Sync Started Feb 23 19:10:11 volumio sudo[21756]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:11 volumio volumio[21499]: Error adding Membership: Error: addMembership EINVAL Feb 23 19:10:11 volumio volumio[21499]: info: Shairport-Sync Started Feb 23 19:10:11 volumio volumio[21499]: info: Shairport-Sync Started Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=debug msg="app state loaded" Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: X display number found: 0 Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: X display number found: 0 Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: X display number found: 0 Feb 23 19:10:11 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:11 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 19:10:11 volumio go-librespot[21794]: time="2026-02-23T19:10:11+01:00" level=info msg="zeroconf server listening on port 34583" Feb 23 19:10:11 volumio volumio[21499]: info: touch_display: Setting screensaver timeout to 200 seconds. Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="obtained new client token: AAD/5QUvmpEWah7zJY3VhApUR9T2vijsdj9hoveo6I4jrQZOYbj23kJeEHQnmQHD0KLjj1shwEAVmmdjf8AMvWhFaj59P8dN941iiLkX8Uxvr9o8KdqQUdEn5J0aFHfMmDUQRtkM7KdoCOE/fXqSez1Nr68Nvx4bZ7O4fB5GpHFNOIJEt/csHJSP+F+EKq+hNQ/wiCzhb2qLFAU0bJumklEOA/UU+r6J0BxOCZ2DOIsPMR3yURgW" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="completed challenge" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=debug msg="completed challenge" Feb 23 19:10:12 volumio go-librespot[21794]: time="2026-02-23T19:10:12+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:12 volumio volumio[21499]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.230 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 23 19:10:12 volumio volumio[21499]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:12 volumio volumio[21499]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:12 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 19:10:12 volumio volumio[21499]: info: Listing playlists Feb 23 19:10:13 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. Feb 23 19:10:13 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:13 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:13 volumio go-librespot[21794]: time="2026-02-23T19:10:13+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:13 volumio volumio[21499]: info: No valid Plugin REST Endpoint Feb 23 19:10:13 volumio qobuz-connect[21826]: 20260223 19:10:13.228 [21826.21826] ERROR SampleApp: api request was not successful Feb 23 19:10:13 volumio qobuz-connect[21826]: Error: Application ID was not specified! Feb 23 19:10:13 volumio qobuz-connect[21826]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:13 volumio qobuz-connect[21826]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:13 volumio qobuz-connect[21826]: -n|--deviceName: Device name Feb 23 19:10:13 volumio qobuz-connect[21826]: -l|--model: Device's model name Feb 23 19:10:13 volumio qobuz-connect[21826]: -s|--serialNumber: Device's serial number Feb 23 19:10:13 volumio qobuz-connect[21826]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:13 volumio qobuz-connect[21826]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:13 volumio qobuz-connect[21826]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:13 volumio qobuz-connect[21826]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:13 volumio qobuz-connect[21826]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:13 volumio qobuz-connect[21826]: -h|--help: Print this help Feb 23 19:10:13 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:13 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:13 volumio go-librespot[21794]: time="2026-02-23T19:10:13+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:13 volumio go-librespot[21794]: time="2026-02-23T19:10:13+01:00" level=debug msg="completed challenge" Feb 23 19:10:13 volumio go-librespot[21794]: time="2026-02-23T19:10:13+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:13 volumio go-librespot[21794]: time="2026-02-23T19:10:13+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:14 volumio go-librespot[21794]: time="2026-02-23T19:10:14+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:14 volumio go-librespot[21794]: time="2026-02-23T19:10:14+01:00" level=debug msg="completed challenge" Feb 23 19:10:14 volumio go-librespot[21794]: time="2026-02-23T19:10:14+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:14 volumio sudo[21842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 19:10:14 volumio sudo[21844]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:14 volumio sudo[21844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:14 volumio sudo[21842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:14 volumio sudo[21842]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:14 volumio sudo[21844]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:14 volumio sudo[21846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 19:10:14 volumio sudo[21846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:14 volumio sudo[21846]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:14 volumio volumio[21499]: info: Upmpdcli Daemon Started Feb 23 19:10:14 volumio volumio[21499]: info: go-librespot daemon successfully initialized Feb 23 19:10:14 volumio go-librespot[21794]: time="2026-02-23T19:10:14+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 23 19:10:14 volumio go-librespot[21794]: time="2026-02-23T19:10:14+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:14 volumio go-librespot[21794]: time="2026-02-23T19:10:14+01:00" level=debug msg="completed challenge" Feb 23 19:10:15 volumio go-librespot[21794]: time="2026-02-23T19:10:15+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:15 volumio go-librespot[21794]: time="2026-02-23T19:10:15+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 23 19:10:15 volumio go-librespot[21794]: time="2026-02-23T19:10:15+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:55858->104.199.65.9:80: read: connection reset by peer" Feb 23 19:10:15 volumio go-librespot[21794]: time="2026-02-23T19:10:15+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:55858->104.199.65.9:80: read: connection reset by peer" Feb 23 19:10:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:16 volumio volumio[21499]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 23 19:10:17 volumio volumio[21499]: info: Initializing connection to go-librespot Websocket Feb 23 19:10:17 volumio volumio[21499]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 19:10:18 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. Feb 23 19:10:18 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:18 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:18 volumio volumio[21499]: info: No valid Plugin REST Endpoint Feb 23 19:10:18 volumio qobuz-connect[21850]: 20260223 19:10:18.464 [21850.21850] ERROR SampleApp: api request was not successful Feb 23 19:10:18 volumio qobuz-connect[21850]: Error: Application ID was not specified! Feb 23 19:10:18 volumio qobuz-connect[21850]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:18 volumio qobuz-connect[21850]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:18 volumio qobuz-connect[21850]: -n|--deviceName: Device name Feb 23 19:10:18 volumio qobuz-connect[21850]: -l|--model: Device's model name Feb 23 19:10:18 volumio qobuz-connect[21850]: -s|--serialNumber: Device's serial number Feb 23 19:10:18 volumio qobuz-connect[21850]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:18 volumio qobuz-connect[21850]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:18 volumio qobuz-connect[21850]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:18 volumio qobuz-connect[21850]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:18 volumio qobuz-connect[21850]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:18 volumio qobuz-connect[21850]: -h|--help: Print this help Feb 23 19:10:18 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:18 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 23 19:10:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:18 volumio go-librespot[21851]: go-librespot daemon starting... Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=debug msg="app state loaded" Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 19:10:18 volumio go-librespot[21852]: time="2026-02-23T19:10:18+01:00" level=info msg="zeroconf server listening on port 42217" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="obtained new client token: AABYPcSg2ugzHOaLgu5lI+mWhvDHEpKbzxLzfPNkdcYn7f0sW7g6I6lF1jQODskrj/zC5nWeYqWEtRZ/Sn2YuO+j1+v0k1EFw2vGHG+ILeEcJxuWONR4BQjIREDi9lH6bcPsCQfsRkGDoBVJEWpo/D/sBm4SkY+cH6HM3zQYDtEoAr2rqsKmfj1RdPm9joC+8/BsXMV+VYloppslfkX6s/dMJNiNH/QA3caPhvKgkjw1rZInY/8p" Feb 23 19:10:19 volumio bluealsa[1041]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 23 19:10:19 volumio bluealsa[1041]: ../src/ba-transport.c:319: New A2DP transport: 15 Feb 23 19:10:19 volumio bluealsa[1041]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:1021 W:1024 Feb 23 19:10:19 volumio bluealsa[1041]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 23 19:10:19 volumio bluealsa[1041]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Feb 23 19:10:19 volumio bluealsa[1041]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Feb 23 19:10:19 volumio bluealsa[1041]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Feb 23 19:10:19 volumio bluealsa[1041]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="completed challenge" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=debug msg="completed challenge" Feb 23 19:10:19 volumio go-librespot[21852]: time="2026-02-23T19:10:19+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 19:10:19 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 19:10:20 volumio go-librespot[21852]: time="2026-02-23T19:10:20+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:20 volumio go-librespot[21852]: time="2026-02-23T19:10:20+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:20 volumio go-librespot[21852]: time="2026-02-23T19:10:20+01:00" level=debug msg="completed challenge" Feb 23 19:10:20 volumio go-librespot[21852]: time="2026-02-23T19:10:20+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:20 volumio volumio[21499]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 19:10:20 volumio volumio[21499]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 19:10:20 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:20 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:20 volumio volumio[21499]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 19:10:20 volumio volumio[21499]: info: MyVolumio login type: Token Feb 23 19:10:20 volumio volumio[21499]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 19:10:20 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 19:10:21 volumio go-librespot[21852]: time="2026-02-23T19:10:21+01:00" level=debug msg="connected to ap-gue1.spotify.com:443" Feb 23 19:10:21 volumio go-librespot[21852]: time="2026-02-23T19:10:21+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:21 volumio go-librespot[21852]: time="2026-02-23T19:10:21+01:00" level=debug msg="completed challenge" Feb 23 19:10:21 volumio go-librespot[21852]: time="2026-02-23T19:10:21+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:21 volumio volumio[21499]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 19:10:21 volumio volumio[21499]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 19:10:21 volumio volumio[21499]: info: Streaming services startup Feb 23 19:10:21 volumio volumio[21499]: info: Starting Streaming Daemon Feb 23 19:10:21 volumio sudo[21863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 19:10:21 volumio sudo[21863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:21 volumio volumio[21499]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 19:10:21 volumio sudo[21863]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:21 volumio volumio[21499]: info: Initializing connection to go-librespot Websocket Feb 23 19:10:21 volumio volumio[21499]: error: Cannot start Volumio Streaming Daemon Feb 23 19:10:21 volumio volumio[21499]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 19:10:21 volumio volumio[21499]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 19:10:21 volumio go-librespot[21852]: time="2026-02-23T19:10:21+01:00" level=debug msg="new websocket client" Feb 23 19:10:21 volumio volumio[21499]: info: Connection to go-librespot Websocket established Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:55872->104.199.65.9:80: read: connection reset by peer" Feb 23 19:10:22 volumio volumio[21499]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=debug msg="completed challenge" Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:22 volumio go-librespot[21852]: time="2026-02-23T19:10:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:22 volumio volumio[21499]: info: Connection to go-librespot Websocket closed Feb 23 19:10:22 volumio volumio[21499]: info: MyVolumio token set successfully Feb 23 19:10:22 volumio volumio[21499]: info: MYVOLUMIO: Adding device Feb 23 19:10:22 volumio volumio[21499]: info: MYVOLUMIO: Evaluating Server Feb 23 19:10:22 volumio volumio[21499]: info: MyVolumio Plan changed: premium Feb 23 19:10:22 volumio volumio[21499]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 23 19:10:22 volumio volumio[21499]: info: Removing browser output: myVolumio user plan is not superstar Feb 23 19:10:22 volumio volumio[21499]: info: Removing audio output: Feb 23 19:10:22 volumio volumio[21499]: info: MYVOLUMIO: Adding device Feb 23 19:10:22 volumio volumio[21499]: info: MYVOLUMIO: Evaluating Server Feb 23 19:10:22 volumio volumio[21499]: info: Remote config written successfully Feb 23 19:10:22 volumio volumio[21499]: info: Starting Tunnel 1 Feb 23 19:10:22 volumio volumio[21499]: info: Starting Tunnel Connection Checker Feb 23 19:10:22 volumio volumio[21499]: info: Completed starting MyVolumio Plugin Feb 23 19:10:23 volumio volumio[21499]: info: MYVolumio Device enabled Feb 23 19:10:23 volumio volumio[21499]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 23 19:10:23 volumio volumio[21499]: info: MyVolumio status changed Feb 23 19:10:23 volumio volumio[21499]: info: Streaming services startup Feb 23 19:10:23 volumio volumio[21499]: info: Starting Streaming Daemon Feb 23 19:10:23 volumio sudo[21910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 19:10:23 volumio sudo[21910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:23 volumio volumio[21499]: info: Setting Geolocation for MyVolumio to eu6 Feb 23 19:10:23 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:23 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:23 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:23 volumio sudo[21910]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:23 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 23 19:10:23 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 23 19:10:23 volumio volumio[21499]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 23 19:10:23 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 23 19:10:23 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. Feb 23 19:10:23 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:23 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 23 19:10:24 volumio sudo[21930]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 23 19:10:24 volumio sudo[21930]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:24 volumio sudo[21930]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:24 volumio volumio[21499]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 23 19:10:24 volumio volumio[21499]: info: MRS: MultiRoom plugin initialized Feb 23 19:10:24 volumio volumio[21499]: info: MRS: STOPPING SNAPCLIENT Feb 23 19:10:24 volumio volumio[21499]: info: MRS: Snap server stop Feb 23 19:10:24 volumio volumio[21499]: info: MRS: STOPPING volumioStreaming Feb 23 19:10:24 volumio sudo[21947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 23 19:10:24 volumio sudo[21947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 23 19:10:24 volumio sudo[21949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 23 19:10:24 volumio sudo[21949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:24 volumio sudo[21951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 23 19:10:24 volumio sudo[21951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:24 volumio sudo[21954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 23 19:10:24 volumio sudo[21954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:24 volumio sudo[21954]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 23 19:10:24 volumio sudo[21947]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 23 19:10:24 volumio sudo[21949]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:24 volumio sudo[21951]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 23 19:10:24 volumio volumio[21499]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 23 19:10:24 volumio volumio[21499]: info: Preparing to generate the ALSA configuration file Feb 23 19:10:25 volumio volumio[21499]: info: Updating MyVolumio device info Feb 23 19:10:25 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:25 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:25 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:25 volumio volumio[21499]: info: Getting Spotify volume Feb 23 19:10:25 volumio volumio[21499]: info: Setting Geolocation for MyVolumio to eu6 Feb 23 19:10:25 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:25 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:25 volumio volumio[21499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:25 volumio volumio[21499]: info: Successfully Added MyVolumio device Feb 23 19:10:25 volumio volumio[21499]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 19:10:25 volumio volumio[21499]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 19:10:25 volumio volumio[21499]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 23 19:10:25 volumio volumio[21499]: errno: -111, Feb 23 19:10:25 volumio volumio[21499]: code: 'ECONNREFUSED', Feb 23 19:10:25 volumio volumio[21499]: syscall: 'connect', Feb 23 19:10:25 volumio volumio[21499]: address: '127.0.0.1', Feb 23 19:10:25 volumio volumio[21499]: port: 9879, Feb 23 19:10:25 volumio volumio[21499]: response: undefined Feb 23 19:10:25 volumio volumio[21499]: } Feb 23 19:10:25 volumio volumio[21499]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 19:10:25 volumio sudo[21972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 19:09' Feb 23 19:10:25 volumio sudo[21972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:25 volumio sudo[21972]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 23 19:10:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:25 volumio go-librespot[21974]: go-librespot daemon starting... Feb 23 19:10:25 volumio go-librespot[21980]: time="2026-02-23T19:10:25+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:25 volumio go-librespot[21980]: time="2026-02-23T19:10:25+01:00" level=debug msg="app state loaded" Feb 23 19:10:25 volumio go-librespot[21980]: time="2026-02-23T19:10:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=info msg="zeroconf server listening on port 36131" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="obtained new client token: AAAdmpMtszXlvgFuh6JmgS5SDyo8oOmEWHjvltq302NimsxW9VB5J6SOSUM9ZFYePmj/E64x1gHVQZdi5vdQiXfYcP15+rYKojI35si6JHz/NI05ZUSje8TA/RJTUKcWwvDOZj8Lv1JEtZr6pEtvsjexP2wlJrTX52MJKyWJL20gtiAuU1dIT5NqzHikAiG7GitJ6EL0HT4fh58IVL81oAwc33CuxVkzXUnuNmH28YDA1OAmmxmw" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Feb 23 19:10:26 volumio qobuz-connect[21913]: Error: Application ID was not specified! Feb 23 19:10:26 volumio qobuz-connect[21913]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:26 volumio qobuz-connect[21913]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:26 volumio qobuz-connect[21913]: -n|--deviceName: Device name Feb 23 19:10:26 volumio qobuz-connect[21913]: -l|--model: Device's model name Feb 23 19:10:26 volumio qobuz-connect[21913]: -s|--serialNumber: Device's serial number Feb 23 19:10:26 volumio qobuz-connect[21913]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:26 volumio qobuz-connect[21913]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:26 volumio qobuz-connect[21913]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:26 volumio qobuz-connect[21913]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:26 volumio qobuz-connect[21913]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:26 volumio qobuz-connect[21913]: -h|--help: Print this help Feb 23 19:10:26 volumio volumio-remote-updater[735]: [2026-02-23 18:10:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 19:10:26 volumio volumio-remote-updater[735]: [2026-02-23 18:10:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 19:10:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:26 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:26 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:26.280Z level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 23 19:10:26 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:26 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:26.292Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Feb 23 19:10:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 19:10:26 volumio systemd[1]: volumio.service: Consumed 25.299s CPU time. Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Feb 23 19:10:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 23 19:10:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=debug msg="completed challenge" Feb 23 19:10:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 30. Feb 23 19:10:26 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 23 19:10:26 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 23 19:10:26 volumio systemd[1]: volumio.service: Consumed 25.299s CPU time. Feb 23 19:10:26 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 23 19:10:26 volumio go-librespot[21980]: time="2026-02-23T19:10:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:26 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 23 19:10:27 volumio go-librespot[21980]: time="2026-02-23T19:10:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 23 19:10:27 volumio go-librespot[21980]: time="2026-02-23T19:10:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:59626->104.199.65.9:443: read: connection reset by peer" Feb 23 19:10:27 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:27.294Z level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 23 19:10:27 volumio go-librespot[21980]: time="2026-02-23T19:10:27+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 23 19:10:28 volumio go-librespot[21980]: time="2026-02-23T19:10:28+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:28 volumio go-librespot[21980]: time="2026-02-23T19:10:28+01:00" level=debug msg="completed challenge" Feb 23 19:10:28 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:28 volumio volumio[21997]: info: ----- Volumio3 ---- Feb 23 19:10:28 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:28 volumio volumio[21997]: info: ----- System startup ---- Feb 23 19:10:28 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:28 volumio go-librespot[21980]: time="2026-02-23T19:10:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:28 volumio go-librespot[21980]: time="2026-02-23T19:10:28+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:28 volumio go-librespot[21980]: time="2026-02-23T19:10:28+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:28 volumio go-librespot[21980]: time="2026-02-23T19:10:28+01:00" level=debug msg="completed challenge" Feb 23 19:10:28 volumio volumio[21997]: info: MYVOLUMIO Environment detected Feb 23 19:10:29 volumio go-librespot[21980]: time="2026-02-23T19:10:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:29 volumio volumio[21997]: info: Plugin folders cleanup Feb 23 19:10:29 volumio volumio[21997]: info: Scanning into folder /volumio/app/plugins/ Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category audio_interface Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category miscellanea Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category music_service Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category plugins.json Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category system_controller Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category user_interface Feb 23 19:10:29 volumio volumio[21997]: info: Scanning into folder /data/plugins/ Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category music_service Feb 23 19:10:29 volumio volumio[21997]: info: Scanning category user_interface Feb 23 19:10:29 volumio volumio[21997]: info: Plugin folders cleanup completed Feb 23 19:10:29 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:29 volumio volumio[21997]: info: ----- Core plugins startup ---- Feb 23 19:10:29 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 19:10:29 volumio volumio[21997]: info: Adding plugin upnp to MyMusic Plugins Feb 23 19:10:29 volumio volumio[21997]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 19:10:29 volumio volumio[21997]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugins from folder /data/plugins/ Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "system"... Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "appearance"... Feb 23 19:10:29 volumio go-librespot[21980]: time="2026-02-23T19:10:29+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:29 volumio go-librespot[21980]: time="2026-02-23T19:10:29+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:29 volumio go-librespot[21980]: time="2026-02-23T19:10:29+01:00" level=debug msg="completed challenge" Feb 23 19:10:29 volumio go-librespot[21980]: time="2026-02-23T19:10:29+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "network"... Feb 23 19:10:29 volumio volumio[21997]: info: Refreshing Cached IP Addresses Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "services"... Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "volumio5onboarding"... Feb 23 19:10:29 volumio sudo[22027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:29 volumio sudo[22027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:29 volumio sudo[22027]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:29 volumio sudo[22025]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 19:10:29 volumio sudo[22040]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 19:10:29 volumio sudo[22025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:29 volumio sudo[22040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "alsa_controller"... Feb 23 19:10:29 volumio sudo[22025]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:29 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:29 volumio volumio[21997]: info: Loading plugin "wizard"... Feb 23 19:10:30 volumio volumio[21997]: info: Loading plugin "networkfs"... Feb 23 19:10:30 volumio volumio[21997]: info: Starting Udev Watcher for removable devices Feb 23 19:10:30 volumio volumio[21997]: info: Ignoring mount for partition: boot Feb 23 19:10:30 volumio volumio[21997]: info: Ignoring mount for partition: volumio Feb 23 19:10:30 volumio volumio[21997]: info: Ignoring mount for partition: volumio_data Feb 23 19:10:30 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 19:10:30 volumio volumio[21997]: info: Loading plugin "volumio_command_line_client"... Feb 23 19:10:30 volumio volumio[21997]: info: Loading plugin "upnp"... Feb 23 19:10:30 volumio volumio[21997]: info: [1771870230060] Starting Upmpd Daemon Feb 23 19:10:30 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 19:10:30 volumio volumio[21997]: info: Loading plugin "my_music"... Feb 23 19:10:30 volumio volumio[21997]: info: Loading plugin "mpd"... Feb 23 19:10:30 volumio go-librespot[21980]: time="2026-02-23T19:10:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:30 volumio go-librespot[21980]: time="2026-02-23T19:10:30+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:30 volumio go-librespot[21980]: time="2026-02-23T19:10:30+01:00" level=debug msg="completed challenge" Feb 23 19:10:30 volumio go-librespot[21980]: time="2026-02-23T19:10:30+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:30 volumio go-librespot[21980]: time="2026-02-23T19:10:30+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:30 volumio volumio[21997]: info: Loading plugin "upnp_browser"... Feb 23 19:10:31 volumio volumio[21997]: info: Starting UPNP Browser Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "alarm-clock"... Feb 23 19:10:31 volumio volumio-remote-updater[735]: [2026-02-23 18:10:31] [connect] Successful connection Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "airplay_emulation"... Feb 23 19:10:31 volumio volumio[21997]: info: Starting Shairport Sync Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "last_100"... Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "webradio"... Feb 23 19:10:31 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5. Feb 23 19:10:31 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "i2s_dacs"... Feb 23 19:10:31 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:31 volumio volumio[21997]: info: I2S DAC not set, start Auto-detection Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "volumiodiscovery"... Feb 23 19:10:31 volumio volumio[21997]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:31 volumio volumio[21997]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:31 volumio volumio[21997]: *** WARNING *** For more information see Feb 23 19:10:31 volumio volumio[21997]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:31 volumio volumio[21997]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:31 volumio volumio[21997]: *** WARNING *** For more information see Feb 23 19:10:31 volumio node[21997]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:31 volumio node[21997]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:31 volumio node[21997]: *** WARNING *** For more information see Feb 23 19:10:31 volumio volumio[21997]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 19:10:31 volumio volumio[21997]: info: Discovery: Started advertising with name: Volumio Feb 23 19:10:31 volumio node[21997]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 19:10:31 volumio node[21997]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 19:10:31 volumio node[21997]: *** WARNING *** For more information see Feb 23 19:10:31 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "soundcloud"... Feb 23 19:10:31 volumio volumio[21997]: info: Loading plugin "spop"... Feb 23 19:10:32 volumio sudo[22040]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "outputs"... Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "albumart"... Feb 23 19:10:32 volumio volumio[21997]: info: Plugin example_plugin is not enabled Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "inputs"... Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "updater_comm"... Feb 23 19:10:32 volumio volumio[21997]: info: Plugin mpdemulation is not enabled Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "rest_api"... Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "websocket"... Feb 23 19:10:32 volumio volumio[21997]: info: Starting Socket.io Server version 1.7.4 Feb 23 19:10:32 volumio volumio[21997]: info: Loading plugin "touch_display"... Feb 23 19:10:32 volumio volumio[21997]: info: Applying required configuration parameters for plugin touch_display Feb 23 19:10:32 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:32.895Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:32 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:32.895Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:32 volumio volumio[21997]: info: Loading i18n strings for locale it Feb 23 19:10:32 volumio volumio[21997]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 23 19:10:32 volumio volumio[22062]: Forking 3 albumart workers Feb 23 19:10:32 volumio volumio[21997]: Updating browse sources language Feb 23 19:10:32 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::initPlayerControls Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: Express server listening on port 3000 Feb 23 19:10:33 volumio volumio[21997]: [Metrics] WebUI: 5s 320.41ms Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::resetVolumioState Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::getcurrentVolume Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: Volumio Network Manager: Network status updated: 1 Feb 23 19:10:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 23 19:10:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Feb 23 19:10:33 volumio volumio[21997]: info: VolumeController:: Volume=94 Mute =false Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::pushState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio go-librespot[22109]: go-librespot daemon starting... Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 19:10:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::updateTrackBlock Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrackBlock Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="app state loaded" Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:33 volumio volumio[21997]: info: Reloading queue from file Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.230 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Feb 23 19:10:33 volumio volumio[21997]: info: Setting Device type: Raspberry PI Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::setRepeat null single undefined Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::pushState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::setRandom false Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::pushState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:33 volumio volumio-remote-updater[735]: [2026-02-23 18:10:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771870231 101 Feb 23 19:10:33 volumio volumio[21997]: 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: 3 Feb 23 19:10:33 volumio volumio[21997]: info: No valid Plugin REST Endpoint Feb 23 19:10:33 volumio qobuz-connect[22058]: 20260223 19:10:33.694 [22058.22058] ERROR SampleApp: api request was not successful Feb 23 19:10:33 volumio qobuz-connect[22058]: Error: Application ID was not specified! Feb 23 19:10:33 volumio qobuz-connect[22058]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:33 volumio qobuz-connect[22058]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:33 volumio qobuz-connect[22058]: -n|--deviceName: Device name Feb 23 19:10:33 volumio qobuz-connect[22058]: -l|--model: Device's model name Feb 23 19:10:33 volumio qobuz-connect[22058]: -s|--serialNumber: Device's serial number Feb 23 19:10:33 volumio qobuz-connect[22058]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:33 volumio qobuz-connect[22058]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:33 volumio qobuz-connect[22058]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:33 volumio qobuz-connect[22058]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:33 volumio qobuz-connect[22058]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:33 volumio qobuz-connect[22058]: -h|--help: Print this help Feb 23 19:10:33 volumio volumio[21997]: info: VolumeController:: Volume=94 Mute =false Feb 23 19:10:33 volumio volumio[21997]: info: CoreStateMachine::pushState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioPushState Feb 23 19:10:33 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:33 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 19:10:33 volumio volumio[21997]: info: Discovery: Getting this device information Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=info msg="zeroconf server listening on port 46171" Feb 23 19:10:33 volumio volumio[21997]: info: Discovery: adding 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 23 19:10:33 volumio volumio[21997]: info: Discovery: Found device Volumio Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio volumio[21997]: info: Discovery: this is already registered, 5fb3d0ef-ca0b-435e-a8d6-14a06ccc3359 Feb 23 19:10:33 volumio volumio[21997]: info: Discovery: Found device Volumio Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:33 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:33 volumio volumio[21997]: info: Completed loading Core Plugins Feb 23 19:10:33 volumio volumio[21997]: info: Preparing to generate the ALSA configuration file Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="obtained new client token: AAD1VYh5RyZh1OzkvcZ4aadN1vcYu0wxjZUuLgOIJ2MSR+pNK3eImOi3e4peb2XRc7tWujkh3E3LJpLd1ggh4GWj1ysky56r4Y3iuGoJy7HcEAAHEkmTfwTRiuCVZNKEuxMh+LqglGZQ4+UnjmfP7RxqMcWFjvKtDDPY+VOGrmdbnPQXYJDqDuYhAuCew7zXaarJ/3mJaAKj+B5H0P4jLl4p3Ihq25/vpzLPWVyIMDiQyI+LuYduD5A=" Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to 192.168.0.3:3000 from 192.168.0.230 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 19:10:33 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=debug msg="completed challenge" Feb 23 19:10:33 volumio volumio[21997]: info: Asound.conf file unchanged, so no further update is needed Feb 23 19:10:33 volumio volumio[21997]: info: Output device has changed, restarting MPD Feb 23 19:10:33 volumio go-librespot[22110]: time="2026-02-23T19:10:33+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:33 volumio volumio[21997]: info: Output device has changed, restarting Shairport Sync Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:33 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:33 volumio sudo[22133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 19:10:33 volumio sudo[22133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:34 volumio sudo[22133]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:34 volumio volumio[21997]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:34 volumio volumio[21997]: info: ___________ START PLUGINS ___________ Feb 23 19:10:34 volumio sudo[22135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 19:10:34 volumio sudo[22135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:34 volumio volumio[21997]: info: ControllerMpd::onStart: Initializing MPD Feb 23 19:10:34 volumio volumio[21997]: info: Creating MPD Configuration file Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:34 volumio volumio[21997]: info: [1771870234064] CoreMusicLibrary::Adding element Server multimediali Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:34 volumio sudo[22145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 19:10:34 volumio sudo[22145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:34 volumio sudo[22143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 23 19:10:34 volumio sudo[22145]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:34 volumio sudo[22143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:34 volumio volumio[21997]: info: UPNP Browser: Client initialized successfully Feb 23 19:10:34 volumio sudo[22147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 19:10:34 volumio sudo[22147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:34 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 23 19:10:34 volumio sudo[22143]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:34 volumio volumio[21997]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:34 volumio volumio[22073]: Starting albumart workers Feb 23 19:10:34 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 19:10:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 19:10:34 volumio systemd[1]: mpd.service: Consumed 2.162s CPU time. Feb 23 19:10:34 volumio volumio[21997]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 19:10:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 19:10:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 19:10:34 volumio volumio[22074]: Starting albumart workers Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:34 volumio volumio[21997]: info: [1771870234272] CoreMusicLibrary::Adding element Last_100 Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:34 volumio volumio[21997]: info: [1771870234283] CoreMusicLibrary::Adding element Webradio Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:34 volumio volumio[21997]: info: Initializing BBC Radios Feb 23 19:10:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 19:10:34 volumio volumio[22072]: Starting albumart workers Feb 23 19:10:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 19:10:34 volumio volumio[21997]: info: [1771870234455] CoreMusicLibrary::Adding element SoundCloud Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:34 volumio volumio[21997]: Cannot find translation for source SoundCloud Feb 23 19:10:34 volumio go-librespot[22110]: time="2026-02-23T19:10:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:34 volumio sudo[22165]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 19:10:34 volumio sudo[22165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:34 volumio go-librespot[22110]: time="2026-02-23T19:10:34+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:34 volumio go-librespot[22110]: time="2026-02-23T19:10:34+01:00" level=debug msg="completed challenge" Feb 23 19:10:34 volumio volumio[21997]: info: Creating Spotify config file Feb 23 19:10:34 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:34 volumio sudo[22165]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:34 volumio go-librespot[22110]: time="2026-02-23T19:10:34+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:34 volumio bluealsa[1041]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 23 19:10:34 volumio bluealsa[1041]: ../src/ba-transport-pcm.c:307: Closing BT socket duplicate [15]: 16 Feb 23 19:10:34 volumio bluealsa[1041]: ../src/ba-transport.c:381: Closing A2DP transport: 15 Feb 23 19:10:34 volumio bluealsa[1041]: ../src/ba-transport-pcm.c:257: Exiting IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 23 19:10:35 volumio volumio[21997]: info: Loading i18n strings for locale it Feb 23 19:10:35 volumio volumio[21997]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 19:10:35 volumio volumio[21997]: info: Volumio Calling Home Feb 23 19:10:35 volumio sudo[22190]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 23 19:10:35 volumio sudo[22190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:35 volumio sudo[22190]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=debug msg="completed challenge" Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:35 volumio sudo[22202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 23 19:10:35 volumio sudo[22202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:35 volumio sudo[22204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 23 19:10:35 volumio sudo[22204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:35 volumio sudo[22207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 23 19:10:35 volumio sudo[22207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:35 volumio systemd[1]: Reloading. Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:35 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:35 volumio volumio[21997]: info: touch_display: No backlight interface detected. Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 19:10:35 volumio volumio[21997]: info: Listing playlists Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::volumioGetQueue Feb 23 19:10:35 volumio volumio[21997]: info: CoreStateMachine::getQueue Feb 23 19:10:35 volumio volumio[21997]: info: CorePlayQueue::getQueue Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 19:10:35 volumio volumio[21997]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.230 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 23 19:10:35 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Feb 23 19:10:35 volumio volumio[21997]: info: MPD Permissions set Feb 23 19:10:35 volumio volumio[21997]: info: MPD Permissions set Feb 23 19:10:35 volumio volumio[21997]: info: Volumio called home Feb 23 19:10:35 volumio volumio[21997]: info: Spotify config file written Feb 23 19:10:35 volumio sudo[22212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 23 19:10:35 volumio sudo[22212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:35 volumio sudo[22212]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:35 volumio sudo[22216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 23 19:10:35 volumio sudo[22216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:35 volumio go-librespot[22110]: time="2026-02-23T19:10:35+01:00" level=debug msg="completed challenge" Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:35 volumio volumio[21997]: info: No need to fix Spotify hosts Feb 23 19:10:36 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 19:10:36 volumio volumio[21997]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 23 19:10:36 volumio go-librespot[22110]: time="2026-02-23T19:10:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:36 volumio volumio[21997]: info: An error occurred while refreshing Spotify Token Error: Bad Request Feb 23 19:10:36 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 Feb 23 19:10:36 volumio volumio[21997]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 23 19:10:36 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 Feb 23 19:10:36 volumio volumio[21997]: info: Starting Shairport Sync Feb 23 19:10:36 volumio sudo[22247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 23 19:10:36 volumio sudo[22247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:36 volumio volumio[21997]: info: Starting Shairport Sync Feb 23 19:10:36 volumio sudo[22247]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:36 volumio volumio[21997]: info: Starting Shairport Sync Feb 23 19:10:36 volumio sudo[22250]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 19:10:36 volumio sudo[22250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:36 volumio sudo[22252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 19:10:36 volumio sudo[22252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:36 volumio sudo[22246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 23 19:10:36 volumio sudo[22246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:36 volumio sudo[22255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 23 19:10:36 volumio volumio[21997]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 23 19:10:36 volumio sudo[22255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:36 volumio sudo[22246]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:36 volumio volumio[21997]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 23 19:10:36 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:36 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:36 volumio go-librespot[22110]: time="2026-02-23T19:10:36+01:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused" Feb 23 19:10:36 volumio go-librespot[22110]: time="2026-02-23T19:10:36+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Feb 23 19:10:36 volumio go-librespot[22110]: time="2026-02-23T19:10:36+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:36 volumio go-librespot[22110]: time="2026-02-23T19:10:36+01:00" level=debug msg="completed challenge" Feb 23 19:10:36 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:36 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:36 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:36 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:36 volumio go-librespot[22110]: time="2026-02-23T19:10:36+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:36 volumio sudo[22207]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:36 volumio volumio[21997]: info: touch_display: systemctl daemon-reload succeeded. Feb 23 19:10:36 volumio sudo[22262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 23 19:10:36 volumio sudo[22262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:36 volumio systemd[1]: Reloading. Feb 23 19:10:37 volumio mpd[22175]: 2026-02-23T19:10:37 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 19:10:37 volumio go-librespot[22110]: time="2026-02-23T19:10:37+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:37 volumio go-librespot[22110]: time="2026-02-23T19:10:37+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:37 volumio go-librespot[22110]: time="2026-02-23T19:10:37+01:00" level=debug msg="completed challenge" Feb 23 19:10:37 volumio go-librespot[22110]: time="2026-02-23T19:10:37+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:37 volumio go-librespot[22110]: time="2026-02-23T19:10:37+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:37 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:37 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:37 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:37 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 23 19:10:37 volumio sudo[22204]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:37 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 19:10:37 volumio sudo[22135]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:37 volumio volumio[21997]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 23 19:10:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:37 volumio sudo[22147]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio volumio[21997]: error: MPD error: The expression evaluated to a falsy value: Feb 23 19:10:38 volumio volumio[21997]: assert.ok(self.idling) Feb 23 19:10:38 volumio volumio[21997]: error: The expression evaluated to a falsy value: Feb 23 19:10:38 volumio volumio[21997]: assert.ok(self.idling) Feb 23 19:10:38 volumio volumio[21997]: info: MPD running with PID22175 Feb 23 19:10:38 volumio volumio[21997]: ,establishing connection Feb 23 19:10:38 volumio volumio[21997]: error: updateQueue error: null Feb 23 19:10:38 volumio volumio[21997]: error: updateQueue error: null Feb 23 19:10:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:38 volumio go-librespot[22285]: go-librespot daemon starting... Feb 23 19:10:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 23 19:10:38 volumio sudo[22216]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio sudo[22262]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 23 19:10:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 19:10:38 volumio systemd[1]: shairport-sync.service: Consumed 1.758s CPU time. Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: Volumio Kiosk started. Feb 23 19:10:38 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:38 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:38 volumio volumio[21997]: info: Completed starting Core Plugins Feb 23 19:10:38 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:38 volumio volumio[21997]: info: ----- MyVolumio plugins startup ---- Feb 23 19:10:38 volumio volumio[21997]: info: ------------------------------------------- Feb 23 19:10:38 volumio volumio[21997]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 23 19:10:38 volumio sudo[22202]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="app state loaded" Feb 23 19:10:38 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:38 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:38 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 23 19:10:38 volumio sudo[22255]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio sudo[22252]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 23 19:10:38 volumio sudo[22250]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:38 volumio volumio[21997]: info: Shairport-Sync Started Feb 23 19:10:38 volumio volumio[21997]: Error adding Membership: Error: addMembership EINVAL Feb 23 19:10:38 volumio volumio[21997]: info: Shairport-Sync Started Feb 23 19:10:38 volumio volumio[21997]: info: Shairport-Sync Started Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: X display number found: 0 Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: X display number found: 0 Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=info msg="zeroconf server listening on port 33075" Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: X display number found: 0 Feb 23 19:10:38 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:38 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:38 volumio volumio[21997]: info: touch_display: Setting screensaver timeout to 200 seconds. Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="obtained new client token: AADHgk29gIiDYv421HzlxuXWOmi7IzzkWXnsNLbOMVTYtVgqzXBdwqXPqDmukJCbSbYaQ5vxPnmz2sZlX/EMLX2d6UprLb/Ets+z3m7cQ05Oa55MaABGWnqxgylTGj+SW6rKODD2ssaO3iQfIW3216NMY5l5V1q8AMXgigfwO/dWDMWIgJiM1eABJGyiG4dRkkf6j7Hn48PLrE1NU5Q5PmxzGhR4Cwz6VWqlKyISCj+k9UfARBVPej4=" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=debug msg="completed challenge" Feb 23 19:10:38 volumio go-librespot[22286]: time="2026-02-23T19:10:38+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:38 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 6. Feb 23 19:10:38 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:38 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:39 volumio volumio[21997]: info: No valid Plugin REST Endpoint Feb 23 19:10:39 volumio qobuz-connect[22320]: 20260223 19:10:39.039 [22320.22320] ERROR SampleApp: api request was not successful Feb 23 19:10:39 volumio qobuz-connect[22320]: Error: Application ID was not specified! Feb 23 19:10:39 volumio qobuz-connect[22320]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:39 volumio qobuz-connect[22320]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:39 volumio qobuz-connect[22320]: -n|--deviceName: Device name Feb 23 19:10:39 volumio qobuz-connect[22320]: -l|--model: Device's model name Feb 23 19:10:39 volumio qobuz-connect[22320]: -s|--serialNumber: Device's serial number Feb 23 19:10:39 volumio qobuz-connect[22320]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:39 volumio qobuz-connect[22320]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:39 volumio qobuz-connect[22320]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:39 volumio qobuz-connect[22320]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:39 volumio qobuz-connect[22320]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:39 volumio qobuz-connect[22320]: -h|--help: Print this help Feb 23 19:10:39 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:39 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=debug msg="completed challenge" Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=debug msg="completed challenge" Feb 23 19:10:39 volumio go-librespot[22286]: time="2026-02-23T19:10:39+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:40 volumio sudo[22340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:40 volumio sudo[22338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 19:10:40 volumio sudo[22340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:40 volumio sudo[22338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:40 volumio sudo[22338]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:40 volumio sudo[22340]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:40 volumio sudo[22342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 19:10:40 volumio sudo[22342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:40 volumio volumio[21997]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.230 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 23 19:10:40 volumio sudo[22342]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:40 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:40 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:40 volumio volumio[21997]: info: Upmpdcli Daemon Started Feb 23 19:10:40 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 19:10:40 volumio volumio[21997]: info: Listing playlists Feb 23 19:10:40 volumio go-librespot[22286]: time="2026-02-23T19:10:40+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:40 volumio go-librespot[22286]: time="2026-02-23T19:10:40+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:40 volumio go-librespot[22286]: time="2026-02-23T19:10:40+01:00" level=debug msg="completed challenge" Feb 23 19:10:40 volumio go-librespot[22286]: time="2026-02-23T19:10:40+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:41 volumio volumio[21997]: info: go-librespot daemon successfully initialized Feb 23 19:10:41 volumio go-librespot[22286]: time="2026-02-23T19:10:41+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 23 19:10:41 volumio go-librespot[22286]: time="2026-02-23T19:10:41+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:41 volumio go-librespot[22286]: time="2026-02-23T19:10:41+01:00" level=debug msg="completed challenge" Feb 23 19:10:41 volumio go-librespot[22286]: time="2026-02-23T19:10:41+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:42 volumio go-librespot[22286]: time="2026-02-23T19:10:42+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 23 19:10:42 volumio go-librespot[22286]: time="2026-02-23T19:10:42+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:42 volumio go-librespot[22286]: time="2026-02-23T19:10:42+01:00" level=debug msg="completed challenge" Feb 23 19:10:42 volumio go-librespot[22286]: time="2026-02-23T19:10:42+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:42 volumio go-librespot[22286]: time="2026-02-23T19:10:42+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:42 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:42 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:42 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:42.900Z level=ERROR msg="failed to update discovery on Wi-Fi info change" error="failed to get system info: could not get system info: context deadline exceeded" Feb 23 19:10:42 volumio volumio[21997]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 23 19:10:44 volumio volumio[21997]: info: Initializing connection to go-librespot Websocket Feb 23 19:10:44 volumio volumio[21997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 23 19:10:44 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 7. Feb 23 19:10:44 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:44 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:44 volumio volumio[21997]: info: No valid Plugin REST Endpoint Feb 23 19:10:44 volumio qobuz-connect[22348]: 20260223 19:10:44.292 [22348.22348] ERROR SampleApp: api request was not successful Feb 23 19:10:44 volumio qobuz-connect[22348]: Error: Application ID was not specified! Feb 23 19:10:44 volumio qobuz-connect[22348]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:44 volumio qobuz-connect[22348]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:44 volumio qobuz-connect[22348]: -n|--deviceName: Device name Feb 23 19:10:44 volumio qobuz-connect[22348]: -l|--model: Device's model name Feb 23 19:10:44 volumio qobuz-connect[22348]: -s|--serialNumber: Device's serial number Feb 23 19:10:44 volumio qobuz-connect[22348]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:44 volumio qobuz-connect[22348]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:44 volumio qobuz-connect[22348]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:44 volumio qobuz-connect[22348]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:44 volumio qobuz-connect[22348]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:44 volumio qobuz-connect[22348]: -h|--help: Print this help Feb 23 19:10:44 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:44 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 23 19:10:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:45 volumio go-librespot[22349]: go-librespot daemon starting... Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=debug msg="app state loaded" Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 19:10:45 volumio go-librespot[22350]: time="2026-02-23T19:10:45+01:00" level=info msg="zeroconf server listening on port 40089" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="obtained new client token: AACIGmtnwIHLTBzjt4+NjYTAmr7QQ+7lEUTNhM2492bR2ls2XYJrqHDdD/9UkecRH+UJhtRr5TBARBifBwmo2UHOKc4lLczMrSJ7yL5tbV55FT357bOGv5YkJ+eTgthNljoo+yJzPIryB+EzO3pwWNamjPg9lu1Z+lYm8QK5BUen41qYqgo/Pu+lVi28KAsBgTQzRl6Kbn0rGrOMxutM7p/SHiHTmHfgH0RE51FRCh4kqxR3OhEX" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="completed challenge" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin bluetooth to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin multiroom to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin metavolumio to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin cd_controller to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 23 19:10:46 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=debug msg="completed challenge" Feb 23 19:10:46 volumio go-librespot[22350]: time="2026-02-23T19:10:46+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:47 volumio go-librespot[22350]: time="2026-02-23T19:10:47+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Feb 23 19:10:47 volumio go-librespot[22350]: time="2026-02-23T19:10:47+01:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Feb 23 19:10:47 volumio go-librespot[22350]: time="2026-02-23T19:10:47+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:47 volumio go-librespot[22350]: time="2026-02-23T19:10:47+01:00" level=debug msg="completed challenge" Feb 23 19:10:47 volumio go-librespot[22350]: time="2026-02-23T19:10:47+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:48 volumio go-librespot[22350]: time="2026-02-23T19:10:48+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Feb 23 19:10:48 volumio volumio[21997]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 23 19:10:48 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 23 19:10:48 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:48 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:48 volumio volumio[21997]: info: Starting MyVolumio Remote Streaming Endpoints Feb 23 19:10:48 volumio volumio[21997]: info: MyVolumio login type: Token Feb 23 19:10:48 volumio volumio[21997]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 23 19:10:48 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 23 19:10:48 volumio go-librespot[22350]: time="2026-02-23T19:10:48+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 23 19:10:48 volumio go-librespot[22350]: time="2026-02-23T19:10:48+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:48 volumio go-librespot[22350]: time="2026-02-23T19:10:48+01:00" level=debug msg="completed challenge" Feb 23 19:10:48 volumio go-librespot[22350]: time="2026-02-23T19:10:48+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:49 volumio volumio[21997]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 23 19:10:49 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 23 19:10:49 volumio volumio[21997]: info: Streaming services startup Feb 23 19:10:49 volumio volumio[21997]: info: Starting Streaming Daemon Feb 23 19:10:49 volumio sudo[22361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 19:10:49 volumio sudo[22361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:49 volumio volumio[21997]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 23 19:10:49 volumio sudo[22361]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:49 volumio volumio[21997]: info: Initializing connection to go-librespot Websocket Feb 23 19:10:49 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 23 19:10:49 volumio volumio[21997]: info: Saving new wireless network Feb 23 19:10:49 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 8. Feb 23 19:10:49 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:49 volumio go-librespot[22350]: time="2026-02-23T19:10:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:49 volumio go-librespot[22350]: time="2026-02-23T19:10:49+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:49 volumio go-librespot[22350]: time="2026-02-23T19:10:49+01:00" level=debug msg="completed challenge" Feb 23 19:10:49 volumio volumio[21997]: error: Cannot start Volumio Streaming Daemon Feb 23 19:10:49 volumio volumio[21997]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 19:10:49 volumio volumio[21997]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 19:10:49 volumio sudo[22369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Feb 23 19:10:49 volumio sudo[22369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:49 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:49 volumio go-librespot[22350]: time="2026-02-23T19:10:49+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:49 volumio sudo[22369]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:49 volumio go-librespot[22350]: time="2026-02-23T19:10:49+01:00" level=debug msg="new websocket client" Feb 23 19:10:49 volumio volumio[21997]: info: Connection to go-librespot Websocket established Feb 23 19:10:49 volumio volumio[21997]: info: No valid Plugin REST Endpoint Feb 23 19:10:49 volumio qobuz-connect[22367]: 20260223 19:10:49.554 [22367.22367] ERROR SampleApp: api request was not successful Feb 23 19:10:49 volumio qobuz-connect[22367]: Error: Application ID was not specified! Feb 23 19:10:49 volumio qobuz-connect[22367]: Usage: /usr/local/bin/qobuz-connect-daemon [OPTIONS...] Feb 23 19:10:49 volumio qobuz-connect[22367]: -m|--manufacturer: Manufacturer of the device Feb 23 19:10:49 volumio qobuz-connect[22367]: -n|--deviceName: Device name Feb 23 19:10:49 volumio qobuz-connect[22367]: -l|--model: Device's model name Feb 23 19:10:49 volumio qobuz-connect[22367]: -s|--serialNumber: Device's serial number Feb 23 19:10:49 volumio qobuz-connect[22367]: -o|--outputDevice: ALSA PCM device to play audio to Feb 23 19:10:49 volumio qobuz-connect[22367]: -p|--port: Port to run the local configuration HTTP server on Feb 23 19:10:49 volumio qobuz-connect[22367]: -q|--maxAudioQuality: Force maximum supported audio quality [CD, HIRES1, HIRES2, HIRES3] Feb 23 19:10:49 volumio qobuz-connect[22367]: -v|--volumeDevice: ALSA mixer device to use to for volume & mute control Feb 23 19:10:49 volumio qobuz-connect[22367]: -u|--volumeElement: Name of the element found on the volume device to use to for volume & mute control Feb 23 19:10:49 volumio qobuz-connect[22367]: -h|--help: Print this help Feb 23 19:10:49 volumio systemd[1]: qobuz-connect.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:49 volumio systemd[1]: qobuz-connect.service: Failed with result 'exit-code'. Feb 23 19:10:49 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 23 19:10:49 volumio sudo[22372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 23 19:10:49 volumio sudo[22372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:49 volumio systemd[1]: Stopping wireless.service - Wireless Services... Feb 23 19:10:49 volumio systemd[1]: wireless.service: Killing process 17822 (wpa_supplicant) with signal SIGKILL. Feb 23 19:10:49 volumio systemd[1]: wireless.service: Deactivated successfully. Feb 23 19:10:49 volumio systemd[1]: Stopped wireless.service - Wireless Services. Feb 23 19:10:49 volumio systemd[1]: wireless.service: Consumed 10.151s CPU time. Feb 23 19:10:49 volumio volumio[21997]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 23 19:10:49 volumio go-librespot[22350]: time="2026-02-23T19:10:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:50 volumio systemd[1]: Starting wireless.service - Wireless Services... Feb 23 19:10:50 volumio go-librespot[22350]: time="2026-02-23T19:10:50+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:50 volumio go-librespot[22350]: time="2026-02-23T19:10:50+01:00" level=debug msg="completed challenge" Feb 23 19:10:50 volumio go-librespot[22350]: time="2026-02-23T19:10:50+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:50 volumio go-librespot[22350]: time="2026-02-23T19:10:50+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:50 volumio volumio[21997]: info: Connection to go-librespot Websocket closed Feb 23 19:10:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: New ethernet state: connected Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: First start: yes Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: === END TRANSITION === Feb 23 19:10:50 volumio wireless.js[22374]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 23 19:10:50 volumio sudo[22407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:50 volumio sudo[22407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:50 volumio sudo[22407]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:50 volumio sudo[22414]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 23 19:10:50 volumio sudo[22414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:50 volumio sudo[22414]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:50 volumio sudo[22417]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 23 19:10:50 volumio sudo[22417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:50 volumio volumio[21997]: info: MyVolumio token set successfully Feb 23 19:10:50 volumio volumio[21997]: info: MYVOLUMIO: Adding device Feb 23 19:10:50 volumio volumio[21997]: info: MYVOLUMIO: Evaluating Server Feb 23 19:10:51 volumio volumio[21997]: info: MyVolumio Plan changed: premium Feb 23 19:10:51 volumio volumio[21997]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 23 19:10:51 volumio volumio[21997]: info: Removing browser output: myVolumio user plan is not superstar Feb 23 19:10:51 volumio volumio[21997]: info: Removing audio output: Feb 23 19:10:51 volumio volumio[21997]: info: MYVOLUMIO: Adding device Feb 23 19:10:51 volumio volumio[21997]: info: MYVOLUMIO: Evaluating Server Feb 23 19:10:51 volumio volumio[21997]: info: Remote config written successfully Feb 23 19:10:51 volumio volumio[21997]: info: Starting Tunnel 1 Feb 23 19:10:51 volumio volumio[21997]: info: Starting Tunnel Connection Checker Feb 23 19:10:51 volumio volumio[21997]: info: Completed starting MyVolumio Plugin Feb 23 19:10:51 volumio sudo[22417]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:51 volumio wireless.js[22374]: WIRELESS.JS - INFO: Cleaning previous... Feb 23 19:10:51 volumio sudo[22446]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 23 19:10:51 volumio sudo[22446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:51 volumio sudo[22446]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:51 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 23 19:10:51 volumio volumio[21997]: info: MYVolumio Device enabled Feb 23 19:10:51 volumio volumio[21997]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 23 19:10:51 volumio volumio[21997]: info: MyVolumio status changed Feb 23 19:10:51 volumio volumio[21997]: info: Streaming services startup Feb 23 19:10:51 volumio volumio[21997]: info: Starting Streaming Daemon Feb 23 19:10:51 volumio wireless.js[22374]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Feb 23 19:10:51 volumio wireless.js[22374]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 6ms Feb 23 19:10:51 volumio wireless.js[22374]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 88:a2:9e:a6:59:46) Feb 23 19:10:51 volumio volumio[21997]: info: Setting Geolocation for MyVolumio to eu6 Feb 23 19:10:51 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:51 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:51 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:51 volumio sudo[22463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 23 19:10:51 volumio sudo[22463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:51 volumio sudo[22466]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 23 19:10:51 volumio sudo[22466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:51 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 23 19:10:51 volumio sudo[22471]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:51 volumio sudo[22471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:51 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 23 19:10:51 volumio sudo[22471]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:51 volumio sudo[22463]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:51 volumio sudo[22466]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:51 volumio sudo[22478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 23 19:10:51 volumio sudo[22478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:51 volumio volumio[21997]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 23 19:10:51 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 23 19:10:51 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:51.984Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:51 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:51.985Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 23 19:10:53 volumio sudo[22484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 23 19:10:53 volumio sudo[22484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 23 19:10:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:53 volumio sudo[22484]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio volumio[21997]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 23 19:10:53 volumio volumio[21997]: info: MRS: MultiRoom plugin initialized Feb 23 19:10:53 volumio volumio[21997]: info: MRS: STOPPING SNAPCLIENT Feb 23 19:10:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 23 19:10:53 volumio go-librespot[22486]: go-librespot daemon starting... Feb 23 19:10:53 volumio volumio[21997]: info: MRS: Snap server stop Feb 23 19:10:53 volumio volumio[21997]: info: MRS: STOPPING volumioStreaming Feb 23 19:10:53 volumio sudo[22502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 23 19:10:53 volumio sudo[22502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=info msg="running go-librespot 0.6.2" Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="app state loaded" Feb 23 19:10:53 volumio sudo[22510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 23 19:10:53 volumio sudo[22510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 23 19:10:53 volumio sudo[22512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 23 19:10:53 volumio sudo[22512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio sudo[22516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 23 19:10:53 volumio sudo[22516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio sudo[22516]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 23 19:10:53 volumio sudo[22502]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 23 19:10:53 volumio sudo[22512]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio sudo[22510]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=info msg="zeroconf server listening on port 40763" Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 23 19:10:53 volumio volumio[21997]: info: Preparing to generate the ALSA configuration file Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="obtained new client token: AADYyrGc+DykyUg2lD4XWoK7/Q4hcExbhnuEnlqAbRmC5wO58afG7/dTvgDpprmJjuEc1DWZYWXi0NzUm48UmlqCzKt897yL96Lk4FonyvYMlFub+Fjta4FqOsv5iXIyfLs2aRRkEdIV4bYHGLco/0fpADQ4uu5859sC1UB+yUrbRkQSbdE0VrOOUwGPIihroAYlzAK2nQCyVqf0Nwi4fhgAMl/p+Ne5iaXP38WsCYAQkq/beAifcQg=" Feb 23 19:10:53 volumio volumio[21997]: info: Updating MyVolumio device info Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 23 19:10:53 volumio sudo[22523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:53 volumio sudo[22523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio volumio[21997]: info: Getting Spotify volume Feb 23 19:10:53 volumio volumio[21997]: info: Initializing connection to go-librespot Websocket Feb 23 19:10:53 volumio sudo[22523]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="completed challenge" Feb 23 19:10:53 volumio volumio[21997]: info: Setting Geolocation for MyVolumio to eu11 Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: Successfully Added MyVolumio device Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:53 volumio volumio[21997]: info: Received Get System Info Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 19:10:53 volumio volumio[21997]: info: Discovery: Getting this device information Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:53 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 19:10:53 volumio volumio[21997]: info: MRS: Removed streaming files Feb 23 19:10:53 volumio volumio[21997]: info: MRS: volumioStreaming STOPPED Feb 23 19:10:53 volumio volumio[21997]: info: MRS: SNAPSERVER STOPPED Feb 23 19:10:53 volumio volumio[21997]: info: MRS: SNAPCLIENT STOPPED Feb 23 19:10:53 volumio volumio[21997]: error: Cannot start Volumio Streaming Daemon Feb 23 19:10:53 volumio volumio[21997]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 23 19:10:53 volumio volumio[21997]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 19:10:53 volumio go-librespot[22503]: time="2026-02-23T19:10:53+01:00" level=debug msg="new websocket client" Feb 23 19:10:53 volumio volumio[21997]: info: Connection to go-librespot Websocket established Feb 23 19:10:53 volumio volumio[21997]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 23 19:10:53 volumio volumio[21997]: info: Asound.conf file unchanged, so no further update is needed Feb 23 19:10:53 volumio volumio[21997]: info: Output device has changed, restarting MPD Feb 23 19:10:53 volumio volumio[21997]: info: Output device has changed, restarting Shairport Sync Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:53 volumio sudo[22526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 19:10:53 volumio sudo[22526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio sudo[22528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 19:10:53 volumio sudo[22528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:53 volumio volumio[21997]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 23 19:10:53 volumio sudo[22526]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:53 volumio volumio[21997]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 23 19:10:53 volumio volumio[21997]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 23 19:10:53 volumio volumio[21997]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 23 19:10:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 23 19:10:53 volumio volumio[21997]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 23 19:10:53 volumio volumio[21997]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 23 19:10:53 volumio volumio[21997]: info: MRS: Pushing multiroomSync output for this device Feb 23 19:10:53 volumio volumio[21997]: info: MRS: Pushing multiroomSync output Feb 23 19:10:53 volumio volumio[21997]: info: Adding audio output: Feb 23 19:10:53 volumio volumio[21997]: info: Adding audio output: Feb 23 19:10:53 volumio volumio[21997]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 23 19:10:53 volumio volumio[21997]: info: Adding METAVOLUMIO REST API Endpoints Feb 23 19:10:53 volumio volumio[21997]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 23 19:10:53 volumio volumio[21997]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 23 19:10:53 volumio volumio[21997]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 23 19:10:53 volumio volumio[21997]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 23 19:10:53 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 23 19:10:53 volumio volumio[21997]: info: Preparing CD Folders Feb 23 19:10:53 volumio volumio[21997]: info: Adding CD REST API Endpoints Feb 23 19:10:53 volumio volumio[21997]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 23 19:10:53 volumio volumio[21997]: info: Starting UDEV Watcher for CD Feb 23 19:10:53 volumio volumio[21997]: info: Detecting CD presence with UDEV Feb 23 19:10:53 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 23 19:10:54 volumio sudo[22478]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:54 volumio wireless.js[22374]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: EU Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=debug msg="completed challenge" Feb 23 19:10:54 volumio bluetoothd[957]: Path / reserved for Adv Monitor app :1.901 Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:54 volumio sudo[22547]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set EU Feb 23 19:10:54 volumio sudo[22547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:54 volumio bluetoothd[957]: Adv Monitor app :1.901 disconnected from D-Bus Feb 23 19:10:54 volumio sudo[22547]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:54 volumio wireless.js[22374]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: EU Feb 23 19:10:54 volumio wireless.js[22374]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 23 19:10:54 volumio wireless.js[22374]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 23 19:10:54 volumio wireless.js[22374]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 23 19:10:54 volumio systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 9. Feb 23 19:10:54 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:54 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:54 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:54.697Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:54 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:54.697Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:54 volumio sudo[22558]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 23 19:10:54 volumio sudo[22558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:54 volumio sudo[22558]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:54 volumio sudo[22561]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 23 19:10:54 volumio sudo[22561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:54 volumio sudo[22561]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=debug msg="connected to ap-gew4.spotify.com:80" Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=debug msg="completed challenge" Feb 23 19:10:54 volumio go-librespot[22503]: time="2026-02-23T19:10:54+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:54 volumio wpa_supplicant[22564]: Successfully initialized wpa_supplicant Feb 23 19:10:55 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 23 19:10:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 23 19:10:55 volumio systemd[1]: mpd.service: Consumed 2.855s CPU time. Feb 23 19:10:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 23 19:10:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 23 19:10:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 23 19:10:55 volumio wpa_supplicant[22564]: nl80211: kernel reports: Registration to specific type not supported Feb 23 19:10:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 23 19:10:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 23 19:10:55 volumio wpa_supplicant[22569]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 23 19:10:55 volumio wireless.js[22374]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 4708ms Feb 23 19:10:55 volumio wireless.js[22374]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 23 19:10:55 volumio sudo[22570]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 19:10:55 volumio sudo[22570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 23 19:10:55 volumio sudo[22570]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:55 volumio wireless.js[22374]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 23 19:10:55 volumio systemd[1]: Started wireless.service - Wireless Services. Feb 23 19:10:55 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 23 19:10:55 volumio sudo[22372]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:55 volumio go-librespot[22503]: time="2026-02-23T19:10:55+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Feb 23 19:10:55 volumio go-librespot[22503]: time="2026-02-23T19:10:55+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:55 volumio go-librespot[22503]: time="2026-02-23T19:10:55+01:00" level=debug msg="completed challenge" Feb 23 19:10:55 volumio go-librespot[22503]: time="2026-02-23T19:10:55+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:56 volumio go-librespot[22503]: time="2026-02-23T19:10:56+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 23 19:10:56 volumio go-librespot[22503]: time="2026-02-23T19:10:56+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.3:36166->104.199.65.9:443: read: connection reset by peer" Feb 23 19:10:56 volumio bluealsa[1041]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 23 19:10:56 volumio bluealsa[1041]: ../src/ba-transport.c:319: New A2DP transport: 15 Feb 23 19:10:56 volumio bluealsa[1041]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:1021 W:1024 Feb 23 19:10:56 volumio bluealsa[1041]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Feb 23 19:10:56 volumio bluealsa[1041]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Feb 23 19:10:56 volumio bluealsa[1041]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Feb 23 19:10:56 volumio bluealsa[1041]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Feb 23 19:10:56 volumio bluealsa[1041]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Feb 23 19:10:57 volumio go-librespot[22503]: time="2026-02-23T19:10:57+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Feb 23 19:10:57 volumio wpa_supplicant[22569]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Feb 23 19:10:57 volumio go-librespot[22503]: time="2026-02-23T19:10:57+01:00" level=debug msg="completed keyexchange" Feb 23 19:10:57 volumio go-librespot[22503]: time="2026-02-23T19:10:57+01:00" level=debug msg="completed challenge" Feb 23 19:10:57 volumio mpd[22587]: 2026-02-23T19:10:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 19:10:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 23 19:10:57 volumio sudo[22528]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:57 volumio go-librespot[22503]: time="2026-02-23T19:10:57+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:57 volumio go-librespot[22503]: time="2026-02-23T19:10:57+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Feb 23 19:10:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 23 19:10:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 23 19:10:58 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:58.180Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.211.157:43236 @ 0x1cce5a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:58 volumio volumio5-onboarding[2158]: time=2026-02-23T18:10:58.181Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.230:51532 @ 0x1e0e3c0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 23 19:10:59 volumio volumio[21997]: warn: [cd-plugin] cdspeedctl: device or media not ready Feb 23 19:10:59 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 23 19:10:59 volumio volumio[21997]: info: Adding inputs REST Endpoints Feb 23 19:10:59 volumio volumio[21997]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 23 19:10:59 volumio volumio[21997]: info: Scanning Audio Inputs Feb 23 19:10:59 volumio volumio[21997]: info: Checking against Known Cards name Feb 23 19:10:59 volumio volumio[21997]: info: Adding Server instance for streaming Feb 23 19:10:59 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 23 19:10:59 volumio volumio[21997]: error: Hi Res Audio Failed Login: Missing Login Data Feb 23 19:10:59 volumio volumio[21997]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 23 19:10:59 volumio volumio[21997]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 23 19:10:59 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 23 19:10:59 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 23 19:10:59 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 23 19:10:59 volumio volumio[21997]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 23 19:10:59 volumio volumio[21997]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Feb 23 19:10:59 volumio volumio[21997]: info: QobuzConnect: Starting Qobuz Connect socket and service Feb 23 19:10:59 volumio sudo[22595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 23 19:10:59 volumio sudo[22595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:59 volumio volumio[21997]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Feb 23 19:10:59 volumio volumio[21997]: info: Adding TIDAL REST API Endpoints Feb 23 19:10:59 volumio volumio[21997]: info: Stopping AccessToken refresher cron for QOBUZ Feb 23 19:10:59 volumio sudo[22602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 23 19:10:59 volumio sudo[22602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:59 volumio sudo[22595]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:59 volumio sudo[22602]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:59 volumio volumio[21997]: info: AccessToken refresher cron started for QOBUZ Feb 23 19:10:59 volumio volumio[21997]: info: Adding QOBUZ REST API Endpoints Feb 23 19:10:59 volumio sudo[22605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 23 19:10:59 volumio sudo[22605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:59 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 23 19:10:59 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 23 19:10:59 volumio volumio[21997]: info: Updating MyVolumio device info Feb 23 19:10:59 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 19:10:59 volumio volumio[21997]: info: MRS: Getting audio outputs on start Feb 23 19:10:59 volumio volumio[21997]: info: MRS: Requesting all other devices output Feb 23 19:10:59 volumio sudo[22608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 19:10:59 volumio sudo[22608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 19:10:59 volumio volumio[21997]: info: Getting Spotify volume Feb 23 19:10:59 volumio sudo[22608]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:59 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 23 19:10:59 volumio sudo[22605]: pam_unix(sudo:session): session closed for user root Feb 23 19:10:59 volumio volumio[21997]: info: Successfully Updated MyVolumio device Feb 23 19:10:59 volumio volumio[21997]: info: Received Get System Info Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 19:10:59 volumio volumio[21997]: info: Discovery: Getting this device information Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:59 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 19:10:59 volumio volumio[21997]: info: Received Get System Info Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 19:10:59 volumio volumio[21997]: info: Discovery: Getting this device information Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::volumioGetState Feb 23 19:10:59 volumio volumio[21997]: info: CorePlayQueue::getTrack 0 Feb 23 19:10:59 volumio volumio[21997]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 19:10:59 volumio volumio[21997]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 19:10:59 volumio volumio[21997]: Error: socket hang up Feb 23 19:10:59 volumio volumio[21997]: at connResetException (node:internal/errors:720:14) Feb 23 19:10:59 volumio volumio[21997]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 23 19:10:59 volumio volumio[21997]: at Socket.emit (node:events:526:35) Feb 23 19:10:59 volumio volumio[21997]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 23 19:10:59 volumio volumio[21997]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 23 19:10:59 volumio volumio[21997]: code: 'ECONNRESET', Feb 23 19:10:59 volumio volumio[21997]: response: undefined Feb 23 19:10:59 volumio volumio[21997]: } Feb 23 19:10:59 volumio volumio[21997]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 19:11:00 volumio sudo[22639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 19:10' Feb 23 19:11:00 volumio sudo[22639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"