Oct 02 19:14:00 volumio-ess9038 volumio[1181]: info: MyVolumio login type: Token Oct 02 19:14:00 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 02 19:14:00 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 02 19:14:02 volumio-ess9038 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Oct 02 19:14:03 volumio-ess9038 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:03 volumio-ess9038 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:03 volumio-ess9038 upmpdcli[1888]: Could not open config: /tmp/upmpdcli.conf Oct 02 19:14:03 volumio-ess9038 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:14:03 volumio-ess9038 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 19:14:05 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 02 19:14:05 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 02 19:14:05 volumio-ess9038 volumio[1181]: info: Streaming services startup Oct 02 19:14:05 volumio-ess9038 volumio[1181]: info: Starting Streaming Daemon Oct 02 19:14:06 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 02 19:14:06 volumio-ess9038 volumio5-onboarding[1434]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 02 19:14:06 volumio-ess9038 systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:14:06 volumio-ess9038 systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 02 19:14:06 volumio-ess9038 systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 02 19:14:06 volumio-ess9038 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 19:14:06 volumio-ess9038 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 19:14:07 volumio-ess9038 volumio5-onboarding[1909]: time=2026-10-02T19:14:07.100+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 02 19:14:07 volumio-ess9038 sudo[1904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 19:14:07 volumio-ess9038 sudo[1904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:07 volumio-ess9038 sudo[1904]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 19:14:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 19:14:09 volumio-ess9038 volumio[1181]: error: Cannot start Volumio Streaming Daemon Oct 02 19:14:09 volumio-ess9038 volumio[1181]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 19:14:09 volumio-ess9038 volumio[1181]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 19:14:10 volumio-ess9038 volumio[1181]: 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 Oct 02 19:14:10 volumio-ess9038 volumio[1181]: 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 Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: Received Get System Info Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: Discovery: Getting this device information Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:14:10 volumio-ess9038 volumio5-onboarding[1909]: time=2026-10-02T19:14:10.729+02:00 level=INFO msg="system info for 817815fcae688347580abdfa8b05710f" deviceName="Volumio DAC" deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:10 volumio-ess9038 volumio5-onboarding[1909]: time=2026-10-02T19:14:10.747+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 02 19:14:10 volumio-ess9038 volumio[1181]: error: MyVolumio Custom Token format not valid, refreshing it Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: Received Get System Info Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: Discovery: Getting this device information Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:10 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: Discovery: Getting this device information Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: Listing playlists Oct 02 19:14:11 volumio-ess9038 volumio-remote-updater[685]: Test mode disabled Oct 02 19:14:11 volumio-ess9038 volumio-remote-updater[685]: Alpha mode disabled Oct 02 19:14:11 volumio-ess9038 volumio-remote-updater[685]: Alpha legacy test mode disabled Oct 02 19:14:11 volumio-ess9038 volumio[1181]: info: Listing playlists Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:14:12 volumio-ess9038 volumio-remote-updater[685]: Test mode disabled Oct 02 19:14:12 volumio-ess9038 volumio-remote-updater[685]: Alpha mode disabled Oct 02 19:14:12 volumio-ess9038 volumio-remote-updater[685]: Alpha legacy test mode disabled Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: Received Get System Info Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: Discovery: Getting this device information Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 19:14:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 19:14:13 volumio-ess9038 volumio5-onboarding[1909]: time=2026-10-02T19:14:13.014+02:00 level=INFO msg="enabling local network discovery" Oct 02 19:14:13 volumio-ess9038 volumio5-onboarding[1909]: time=2026-10-02T19:14:13.120+02:00 level=INFO msg="enabling BLE discovery" Oct 02 19:14:13 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:13 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:14 volumio-ess9038 volumio[1181]: info: MyVolumio login type: Token Oct 02 19:14:14 volumio-ess9038 volumio5-onboarding[1909]: time=2026-10-02T19:14:14.488+02:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 02 19:14:15 volumio-ess9038 volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 19:14:16 volumio-ess9038 volumio[1181]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): OpenWeatherMap API key not configured. Add it in Weather Service settings. Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: [now-playing] Weather not configured: Could not fetch weather: OpenWeatherMap API key not configured. Add it in Weather Service settings. Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: Listing playlists Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetQueue Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreStateMachine::getQueue Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getQueue Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:16 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:17 volumio-ess9038 volumio[1181]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 02 19:14:18 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:18 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:18 volumio-ess9038 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Oct 02 19:14:18 volumio-ess9038 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:18 volumio-ess9038 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:18 volumio-ess9038 volumio[1181]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 02 19:14:18 volumio-ess9038 upmpdcli[1945]: Could not open config: /tmp/upmpdcli.conf Oct 02 19:14:18 volumio-ess9038 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:14:18 volumio-ess9038 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: MyVolumio token set successfully Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: MYVOLUMIO: Adding device Oct 02 19:14:20 volumio-ess9038 volumio[1181]: info: MYVOLUMIO: Evaluating Server Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: MyVolumio Plan changed: premium Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: Removing browser output: myVolumio user plan is not superstar Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: Removing audio output: Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: MYVOLUMIO: Adding device Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: MYVOLUMIO: Evaluating Server Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: Setting Geolocation for MyVolumio to eu6 Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:21 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:22 volumio-ess9038 volumio[1181]: info: Remote config written successfully Oct 02 19:14:22 volumio-ess9038 volumio[1181]: info: Starting Tunnel 1 Oct 02 19:14:22 volumio-ess9038 volumio[1181]: info: Starting Tunnel Connection Checker Oct 02 19:14:22 volumio-ess9038 volumio[1181]: info: Completed starting MyVolumio Plugin Oct 02 19:14:22 volumio-ess9038 volumio[1181]: info: Successfully Added MyVolumio device Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: Updating MyVolumio device info Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: Setting Geolocation for MyVolumio to eu4 Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: MYVolumio Device enabled Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: MyVolumio status changed Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: Streaming services startup Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: Starting Streaming Daemon Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: Successfully Updated MyVolumio device Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: Successfully Added MyVolumio device Oct 02 19:14:23 volumio-ess9038 sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 19:14:23 volumio-ess9038 sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:23 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 02 19:14:23 volumio-ess9038 sudo[1985]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:24 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 02 19:14:26 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 02 19:14:26 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 02 19:14:26 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 02 19:14:26 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 02 19:14:32 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 02 19:14:32 volumio-ess9038 sudo[2003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 02 19:14:32 volumio-ess9038 sudo[2003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:32 volumio-ess9038 sudo[2003]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:33 volumio-ess9038 volumio[1181]: info: MRS: MultiRoom plugin initialized Oct 02 19:14:33 volumio-ess9038 volumio[1181]: info: MRS: STOPPING SNAPCLIENT Oct 02 19:14:33 volumio-ess9038 volumio[1181]: info: MRS: Snap server stop Oct 02 19:14:33 volumio-ess9038 volumio[1181]: info: MRS: STOPPING volumioStreaming Oct 02 19:14:33 volumio-ess9038 sudo[2020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 02 19:14:33 volumio-ess9038 sudo[2020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:33 volumio-ess9038 sudo[2022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 02 19:14:33 volumio-ess9038 sudo[2022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:33 volumio-ess9038 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Oct 02 19:14:34 volumio-ess9038 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:34 volumio-ess9038 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:34 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 02 19:14:34 volumio-ess9038 sudo[2027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 02 19:14:34 volumio-ess9038 sudo[2027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:34 volumio-ess9038 sudo[2030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 02 19:14:34 volumio-ess9038 sudo[2020]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:34 volumio-ess9038 sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:34 volumio-ess9038 sudo[2022]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:34 volumio-ess9038 sudo[2030]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:34 volumio-ess9038 sudo[2027]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:34 volumio-ess9038 upmpdcli[2026]: Could not open config: /tmp/upmpdcli.conf Oct 02 19:14:34 volumio-ess9038 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:14:34 volumio-ess9038 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 19:14:34 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 02 19:14:35 volumio-ess9038 volumio[1181]: info: Preparing to generate the ALSA configuration file Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: Reading ALSA contributions from plugins. Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: Updating MyVolumio device info Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: MRS: Removed streaming files Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: MRS: volumioStreaming STOPPED Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: MRS: SNAPSERVER STOPPED Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: MRS: SNAPCLIENT STOPPED Oct 02 19:14:36 volumio-ess9038 volumio[1181]: error: Cannot start Volumio Streaming Daemon Oct 02 19:14:36 volumio-ess9038 volumio[1181]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 19:14:36 volumio-ess9038 volumio[1181]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: Listing playlists Oct 02 19:14:36 volumio-ess9038 volumio[1181]: info: Listing playlists Oct 02 19:14:37 volumio-ess9038 sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 02 19:14:37 volumio-ess9038 sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:37 volumio-ess9038 volumio[1181]: info: Asound.conf file written Oct 02 19:14:37 volumio-ess9038 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. Oct 02 19:14:37 volumio-ess9038 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. Oct 02 19:14:37 volumio-ess9038 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 02 19:14:37 volumio-ess9038 sudo[2053]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:37 volumio-ess9038 sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 02 19:14:37 volumio-ess9038 autossh[2059]: port set to 0, monitoring disabled Oct 02 19:14:37 volumio-ess9038 autossh[2059]: starting ssh (count 1) Oct 02 19:14:37 volumio-ess9038 autossh[2059]: ssh child pid is 2062 Oct 02 19:14:37 volumio-ess9038 sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:37 volumio-ess9038 sudo[2057]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:38 volumio-ess9038 volumiossh-tunnel[2062]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 02 19:14:38 volumio-ess9038 volumio[1181]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 02 19:14:38 volumio-ess9038 volumio[1181]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 02 19:14:38 volumio-ess9038 volumio[1181]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 02 19:14:38 volumio-ess9038 volumio[1181]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Output device has changed, restarting MPD Oct 02 19:14:38 volumio-ess9038 sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 19:14:38 volumio-ess9038 sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 19:14:38 volumio-ess9038 sudo[2068]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 02 19:14:38 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: [FUNC] onStart Oct 02 19:14:38 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Oct 02 19:14:38 volumio-ess9038 sudo[2070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 19:14:38 volumio-ess9038 sudo[2070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:38 volumio-ess9038 systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 19:14:38 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Oct 02 19:14:38 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Oct 02 19:14:38 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: MRS: Pushing multiroomSync output for this device Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: MRS: Pushing multiroomSync output Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding audio output: Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding audio output: Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding METAVOLUMIO REST API Endpoints Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Preparing CD Folders Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding CD REST API Endpoints Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Starting UDEV Watcher for CD Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: Detecting CD presence with UDEV Oct 02 19:14:38 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 02 19:14:39 volumio-ess9038 bluetoothd[897]: Path / reserved for Adv Monitor app :1.28 Oct 02 19:14:39 volumio-ess9038 bluetoothd[897]: Adv Monitor app :1.28 disconnected from D-Bus Oct 02 19:14:42 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:14:42.174: became active device Oct 02 19:14:42 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:14:42.193: paused spotify:track:3UAB5IGVkLgBHxn5vzEn2w "Blood Bank - Spotify Singles" by "Searows" [4:31 / 5:05] Oct 02 19:14:43 volumio-ess9038 systemd[1]: mpd.service: Deactivated successfully. Oct 02 19:14:43 volumio-ess9038 systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 19:14:43 volumio-ess9038 systemd[1]: mpd.service: Consumed 6.643s CPU time. Oct 02 19:14:43 volumio-ess9038 systemd[1]: mpd.socket: Deactivated successfully. Oct 02 19:14:43 volumio-ess9038 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 19:14:43 volumio-ess9038 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 19:14:43 volumio-ess9038 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 19:14:43 volumio-ess9038 systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 19:14:44 volumio-ess9038 sudo[2084]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 19:14:44 volumio-ess9038 sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 19:14:44 volumio-ess9038 sudo[2084]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:44 volumio-ess9038 volumio[1181]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 02 19:14:44 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 02 19:14:44 volumio-ess9038 volumio[1181]: info: Adding inputs REST Endpoints Oct 02 19:14:44 volumio-ess9038 volumio[1181]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 02 19:14:44 volumio-ess9038 volumio[1181]: info: Scanning Audio Inputs Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Checking against Known Cards name Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Adding Server instance for streaming Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 02 19:14:45 volumio-ess9038 volumio[1181]: error: Hi Res Audio Failed Login: Missing Login Data Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Refreshing TIDAL token Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Refreshing QOBUZ token Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 02 19:14:45 volumio-ess9038 sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 19:14:45 volumio-ess9038 sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 02 19:14:45 volumio-ess9038 sudo[2092]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:45 volumio-ess9038 sudo[2099]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 19:14:45 volumio-ess9038 sudo[2099]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:45 volumio-ess9038 volumio[1181]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 02 19:14:46 volumio-ess9038 volumio[1181]: info: MRS: Getting audio outputs on start Oct 02 19:14:46 volumio-ess9038 volumio[1181]: info: MRS: Requesting all other devices output Oct 02 19:14:46 volumio-ess9038 volumio[1181]: info: Successfully Updated MyVolumio device Oct 02 19:14:46 volumio-ess9038 sudo[2099]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:46 volumio-ess9038 sudo[2102]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 19:14:46 volumio-ess9038 sudo[2102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:46 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Oct 02 19:14:46 volumio-ess9038 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 19:14:46 volumio-ess9038 sudo[2102]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:46 volumio-ess9038 volumio[1181]: info: MPD Permissions set Oct 02 19:14:46 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:14:46.436: playing spotify:track:3UAB5IGVkLgBHxn5vzEn2w "Blood Bank - Spotify Singles" by "Searows" [4:31 / 5:05] Oct 02 19:14:46 volumio-ess9038 volumio[1181]: info: Remote SSH Started Oct 02 19:14:46 volumio-ess9038 sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 02 19:14:46 volumio-ess9038 sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:47 volumio-ess9038 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Oct 02 19:14:47 volumio-ess9038 volumiobt[2127]: INFO [BTSTART] Ensuring Bluetooth directory exists... Oct 02 19:14:47 volumio-ess9038 sudo[2128]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Oct 02 19:14:47 volumio-ess9038 sudo[2128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:47 volumio-ess9038 sudo[2106]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:47 volumio-ess9038 sudo[2128]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:47 volumio-ess9038 sudo[2130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Oct 02 19:14:47 volumio-ess9038 sudo[2130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:14:47 volumio-ess9038 sudo[2130]: pam_unix(sudo:session): session closed for user root Oct 02 19:14:47 volumio-ess9038 volumiobt[2132]: INFO [BTSTART] Powering on Bluetooth if needed... Oct 02 19:14:47 volumio-ess9038 bluetoothd[897]: Adv Monitor app :1.29 disconnected from D-Bus Oct 02 19:14:47 volumio-ess9038 volumiobt[2135]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [176B blob data] Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [157B blob data] Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [157B blob data] Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [157B blob data] Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [113B blob data] Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [bluetoothctl]> discoverable on Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [bluetoothctl]> pairable on Oct 02 19:14:48 volumio-ess9038 volumiobt[2136]: [bluetoothctl]> Oct 02 19:14:48 volumio-ess9038 volumiobt[2137]: INFO [BTSTART] Registering Bluetooth agent... Oct 02 19:14:48 volumio-ess9038 bluetoothd[897]: Adv Monitor app :1.30 disconnected from D-Bus Oct 02 19:14:48 volumio-ess9038 volumiobt[2138]: [NEW] Media /org/bluez/hci0 Oct 02 19:14:48 volumio-ess9038 volumiobt[2138]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 19:14:48 volumio-ess9038 volumiobt[2138]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 19:14:48 volumio-ess9038 volumiobt[2138]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 19:14:48 volumio-ess9038 bluetoothd[897]: Adv Monitor app :1.31 disconnected from D-Bus Oct 02 19:14:48 volumio-ess9038 volumiobt[2139]: No agent is registered Oct 02 19:14:48 volumio-ess9038 volumiobt[2139]: [NEW] Media /org/bluez/hci0 Oct 02 19:14:48 volumio-ess9038 volumiobt[2139]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 02 19:14:48 volumio-ess9038 volumiobt[2139]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 02 19:14:48 volumio-ess9038 volumiobt[2139]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 02 19:14:48 volumio-ess9038 bluetoothd[897]: Adv Monitor app :1.32 disconnected from D-Bus Oct 02 19:14:48 volumio-ess9038 volumiobt[2140]: INFO [BTSTART] Agent registered successfully. Oct 02 19:14:48 volumio-ess9038 volumiobt[2141]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: SoloistConnect: open /data/soloist/cache/cache/60/608009aa65a84a5b3c56aaf26c7f9a83476f31f8.file 59507067 bytes over 305s = 1561 kbps -> Lossless Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: CorePlayQueue::getTrack 0 Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: SoloistConnect: taking over playback Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 19:14:49 volumio-ess9038 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Oct 02 19:14:49 volumio-ess9038 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioStop Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: CoreStateMachine::stop Oct 02 19:14:49 volumio-ess9038 volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 19:14:49 volumio-ess9038 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:14:50 volumio-ess9038 upmpdcli[2150]: Could not open config: /tmp/upmpdcli.conf Oct 02 19:14:50 volumio-ess9038 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:14:50 volumio-ess9038 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 19:14:50 volumio-ess9038 volumio[1181]: info: SoloistConnect: alsa held by SoloistRunner M=2117 Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Connecting to system D-Bus Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Connected to system D-Bus Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Enabled Discoverable mode Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Agent registered at /local/a2dpagent Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] Agent set as default Oct 02 19:14:51 volumio-ess9038 volumiobt[2142]: 2026-10-02 19:14:51 a2dp-agent [INFO] A2DP agent running, waiting for connections... Oct 02 19:14:52 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Oct 02 19:14:52 volumio-ess9038 volumio[1181]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Oct 02 19:14:52 volumio-ess9038 volumio[1181]: warn: gpio-buttons: Socket disconnected: ping timeout Oct 02 19:14:53 volumio-ess9038 volumio[1181]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 19:14:54 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:14:55 volumio-ess9038 mpd[2086]: 2026-10-02T19:14:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 19:14:56 volumio-ess9038 systemd[1]: Started mpd.service - Music Player Daemon. Oct 02 19:14:57 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:14:57.130: playing spotify:track:3UAB5IGVkLgBHxn5vzEn2w "Blood Bank - Spotify Singles" by "Searows" [0:00 / 5:05] Oct 02 19:14:57 volumio-ess9038 sudo[2070]: pam_unix(sudo:session): session closed for user root Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: Executing endpoint qc_getconfig Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 19:15:05 volumio-ess9038 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9. Oct 02 19:15:05 volumio-ess9038 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::servicePushState Oct 02 19:15:05 volumio-ess9038 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: CoreStateMachine::pushState Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioPushState Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: MRS: Pushing multiroomSync output update for this device Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: MRS: Pushing multiroomSync output Oct 02 19:15:05 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumioGetState Oct 02 19:15:05 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:05.946 [2104.2104] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 19:15:06 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:06.562 [2104.2104] INFO VolumeManager: [0xfc3f08]: Setting new playback volume: 75 Oct 02 19:15:06 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:06.562 [2104.2104] INFO VolumeManager: [0xfc3f08]: Setting new mute state: 0 Oct 02 19:15:06 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:06.562 [2104.2104] INFO AudioStreamManager: [0xfc3c60]: Setting new audio download buffer size: 1048576 Oct 02 19:15:06 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:06.562 [2104.2104] INFO QobuzConnect: [0xfc47d0]: Client initialized! Oct 02 19:15:06 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:06.562 [2104.2104] INFO SampleApp: Starting Avahi advertising, name: Volumio DAC, service name: _qobuz-connect._tcp Oct 02 19:15:06 volumio-ess9038 volumio[1181]: info: TidalConnect service stoped! Oct 02 19:15:06 volumio-ess9038 volumio[1181]: error: MyVolumio Plugins failed to start in a timely fashion Oct 02 19:15:07 volumio-ess9038 volumio[1181]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Oct 02 19:15:07 volumio-ess9038 volumio[1181]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Oct 02 19:15:07 volumio-ess9038 volumio[1181]: error: Plugin music_service qobuz failed to complete 'onStart' in a timely fashion Oct 02 19:15:07 volumio-ess9038 volumio[1181]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Oct 02 19:15:07 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:15:07.127: playing spotify:track:3UAB5IGVkLgBHxn5vzEn2w "Blood Bank - Spotify Singles" by "Searows" [0:10 / 5:05] Oct 02 19:15:07 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:07.525 [2104.2104] INFO LocalConfigManager: [0xfc3988]: Starting Local Configuration server Oct 02 19:15:07 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:07.525 [2104.2104] INFO SampleApp: Starting Local configuration server Oct 02 19:15:07 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:07.548 [2104.2104] INFO SampleApp: Connected to UNIX socket client 0xfae818 Oct 02 19:15:07 volumio-ess9038 qobuz-connect[2104]: 20261002 19:15:07.548 [2104.2104] INFO SampleApp: Playback volume changed: 75 Oct 02 19:15:08 volumio-ess9038 volumio[1181]: [Metrics] CommandRouter: 129s 718.16ms Oct 02 19:15:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 19:15:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:15:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::Close All Modals sent Oct 02 19:15:08 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::Close All Modals sent Oct 02 19:15:10 volumio-ess9038 upmpdcli[2210]: Could not open config: /tmp/upmpdcli.conf Oct 02 19:15:10 volumio-ess9038 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:15:10 volumio-ess9038 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:11 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 19:15:12 volumio-ess9038 volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 19:15:12 volumio-ess9038 volumio[1181]: warn: QOBUZ login failed: No access token received Oct 02 19:15:12 volumio-ess9038 volumio[1181]: info: Stopping AccessToken refresher cron for QOBUZ Oct 02 19:15:14 volumio-ess9038 volumio[1181]: info: AccessToken refresher cron started for QOBUZ Oct 02 19:15:14 volumio-ess9038 volumio[1181]: info: Adding QOBUZ REST API Endpoints Oct 02 19:15:14 volumio-ess9038 volumio[1181]: error: No access token received for TIDAL Oct 02 19:15:15 volumio-ess9038 volumio[1181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 19:15:16 volumio-ess9038 volumio[1181]: AxiosError: socket hang up Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at AxiosError.from (/myvolumio/plugins/audio_interface/multiroom/node_modules/axios/dist/node/axios.cjs:962:24) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at RedirectableRequest.handleRequestError (/myvolumio/plugins/audio_interface/multiroom/node_modules/axios/dist/node/axios.cjs:3794:29) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at RedirectableRequest.emit (node:events:514:28) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at eventHandlers. (/myvolumio/plugins/audio_interface/multiroom/node_modules/follow-redirects/index.js:49:24) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at ClientRequest.emit (node:events:514:28) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at Socket.socketOnEnd (node:_http_client:519:9) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at Socket.emit (node:events:526:35) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at Axios.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/axios/dist/node/axios.cjs:5110:41) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: isAxiosError: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: code: 'ECONNRESET', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: config: { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: transitional: { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: silentJSONParsing: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: forcedJSONParsing: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: clarifyTimeoutError: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: legacyInterceptorReqResOrdering: true Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: adapter: [ 'xhr', 'http', 'fetch' ], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: transformRequest: [ [Function: transformRequest] ], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: transformResponse: [ [Function: transformResponse] ], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: timeout: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: xsrfCookieName: 'XSRF-TOKEN', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: xsrfHeaderName: 'X-XSRF-TOKEN', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxContentLength: -1, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxBodyLength: -1, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: env: { FormData: [Function], Blob: [class Blob] }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: validateStatus: [Function: validateStatus], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: headers: Object [AxiosHeaders] { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: Accept: 'application/json, text/plain, */*', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'Content-Type': undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'User-Agent': 'axios/1.13.6', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'Accept-Encoding': 'gzip, compress, deflate, br' Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: url: 'http://192.168.1.133:1400/xml/device_description.xml', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: allowAbsoluteUrls: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: method: 'get', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: data: undefined Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: request: Writable { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _writableState: WritableState { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: objectMode: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: highWaterMark: 16384, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: finalCalled: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: needDrain: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: ending: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: ended: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: finished: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: destroyed: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: decodeStrings: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: defaultEncoding: 'utf8', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: length: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: writing: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: corked: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: sync: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: bufferProcessing: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: onwrite: [Function: bound onwrite], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: writecb: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: writelen: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: afterWriteTickInfo: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: buffered: [], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: bufferedIndex: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: allBuffers: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: allNoop: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: pendingcb: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: constructed: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: prefinished: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: errorEmitted: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: emitClose: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: autoDestroy: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: errored: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: closed: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: closeEmitted: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kOnFinished)]: [] Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _events: [Object: null prototype] { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: response: [Function: handleResponse], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: error: [Function: handleRequestError], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: socket: [Array] Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _eventsCount: 3, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _maxListeners: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _options: { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxRedirects: 21, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxBodyLength: Infinity, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: protocol: 'http:', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: path: '/xml/device_description.xml', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: method: 'GET', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: headers: [Object: null prototype], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: agents: [Object], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: auth: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: family: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: beforeRedirect: [Function: dispatchBeforeRedirect], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: beforeRedirects: [Object], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: http2Options: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: hostname: '192.168.1.133', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: port: '1400', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: agent: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: nativeProtocols: [Object], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: pathname: '/xml/device_description.xml' Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _ended: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _ending: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _redirectCount: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _redirects: [], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _requestBodyLength: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _requestBodyBuffers: [], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _onNativeResponse: [Function (anonymous)], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _currentRequest: ClientRequest { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _events: [Object: null prototype], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _eventsCount: 7, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _maxListeners: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: outputData: [], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: outputSize: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: writable: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: destroyed: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _last: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: chunkedEncoding: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: shouldKeepAlive: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxRequestsOnConnectionReached: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _defaultKeepAlive: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: useChunkedEncodingByDefault: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: sendDate: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _removedConnection: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _removedContLen: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _removedTE: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: strictContentLength: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _contentLength: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _hasBody: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _trailer: '', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: finished: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _headerSent: true, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _closed: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: socket: [Socket], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _header: 'GET /xml/device_description.xml HTTP/1.1\r\n' + Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'Accept: application/json, text/plain, */*\r\n' + Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'User-Agent: axios/1.13.6\r\n' + Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'Accept-Encoding: gzip, compress, deflate, br\r\n' + Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'Host: 192.168.1.133:1400\r\n' + Oct 02 19:15:16 volumio-ess9038 volumio[1181]: 'Connection: keep-alive\r\n' + Oct 02 19:15:16 volumio-ess9038 volumio[1181]: '\r\n', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _keepAliveTimeout: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _onPendingData: [Function: nop], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: agent: [Agent], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: socketPath: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: method: 'GET', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxHeaderSize: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: insecureHTTPParser: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: joinDuplicateHeaders: undefined, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: path: '/xml/device_description.xml', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _ended: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: res: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: aborted: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: timeoutCb: [Function: emitRequestTimeout], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: upgradeOrConnect: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: parser: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: maxHeadersCount: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: reusedSocket: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: host: '192.168.1.133', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: protocol: 'http:', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _redirectable: [Circular *1], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kCapture)]: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kBytesWritten)]: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kNeedDrain)]: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(corked)]: 0, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kOutHeaders)]: [Object: null prototype], Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(errored)]: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kHighWaterMark)]: 16384, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kRejectNonStandardBodyWrites)]: false, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kUniqueHeaders)]: null Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _currentUrl: 'http://192.168.1.133:1400/xml/device_description.xml', Oct 02 19:15:16 volumio-ess9038 volumio[1181]: _timeout: null, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: [Symbol(kCapture)]: false Oct 02 19:15:16 volumio-ess9038 volumio[1181]: }, Oct 02 19:15:16 volumio-ess9038 volumio[1181]: cause: Error: socket hang up Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at connResetException (node:internal/errors:720:14) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at Socket.emit (node:events:526:35) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 02 19:15:16 volumio-ess9038 volumio[1181]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 02 19:15:16 volumio-ess9038 volumio[1181]: code: 'ECONNRESET' Oct 02 19:15:16 volumio-ess9038 volumio[1181]: } Oct 02 19:15:16 volumio-ess9038 volumio[1181]: } Oct 02 19:15:16 volumio-ess9038 volumio[1181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 19:15:17 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:15:17.126: playing spotify:track:3UAB5IGVkLgBHxn5vzEn2w "Blood Bank - Spotify Singles" by "Searows" [0:20 / 5:05] Oct 02 19:15:25 volumio-ess9038 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 10. Oct 02 19:15:25 volumio-ess9038 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:15:25 volumio-ess9038 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 19:15:25 volumio-ess9038 upmpdcli[2306]: Could not open config: /tmp/upmpdcli.conf Oct 02 19:15:25 volumio-ess9038 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 19:15:25 volumio-ess9038 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 19:15:26 volumio-ess9038 sudo[2309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 19:14' Oct 02 19:15:26 volumio-ess9038 sudo[2309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 19:15:27 volumio-ess9038 launch-soloist.sh[1584]: 2026-10-02 19:15:27.127: playing spotify:track:3UAB5IGVkLgBHxn5vzEn2w "Blood Bank - Spotify Singles" by "Searows" [0:30 / 5:05] 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"