Oct 11 13:42:03 volumio-ws5 volumio5-onboarding[2357]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:57482->127.0.0.1:3000: i/o timeout Oct 11 13:42:03 volumio-ws5 systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 11 13:42:03 volumio-ws5 systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 11 13:42:03 volumio-ws5 systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 6. Oct 11 13:42:03 volumio-ws5 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 11 13:42:03 volumio-ws5 systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 11 13:42:03 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:03.532+01:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 11 13:42:04 volumio-ws5 volumio[1304]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 13:42:06 volumio-ws5 volumio[1304]: 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 11 13:42:06 volumio-ws5 volumio-remote-updater[843]: Test mode disabled Oct 11 13:42:06 volumio-ws5 volumio-remote-updater[843]: Alpha mode disabled Oct 11 13:42:06 volumio-ws5 volumio-remote-updater[843]: Alpha legacy test mode disabled Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 11 13:42:06 volumio-ws5 volumio[1304]: 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: 9 Oct 11 13:42:06 volumio-ws5 volumio[1304]: 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: 9 Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: Received Get System Info Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: Discovery: Getting this device information Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 13:42:06 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:06.817+01:00 level=INFO msg="system info for 1a00414a08e2c48755e432107327c40a" deviceName=Volumio-WS5 deviceVariant=volumio deviceModel= softwareVersion=4.119 Oct 11 13:42:06 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:06.833+01:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: Received Get System Info Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: Discovery: Getting this device information Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:42:06 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 13:42:07 volumio-ws5 volumio-remote-updater[843]: Test mode disabled Oct 11 13:42:07 volumio-ws5 volumio-remote-updater[843]: Alpha mode disabled Oct 11 13:42:07 volumio-ws5 volumio-remote-updater[843]: Alpha legacy test mode disabled Oct 11 13:42:07 volumio-ws5 volumio[1304]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 11 13:42:07 volumio-ws5 volumio[1304]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 11 13:42:07 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 11 13:42:07 volumio-ws5 volumio[1304]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 11 13:42:07 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: Listing playlists Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetQueue Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreStateMachine::getQueue Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CorePlayQueue::getQueue Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 13:42:08 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 13:42:09 volumio-ws5 volumio[1304]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=7f653b8b2a3b367132e42fc3cf75756d&lat=53.28984268064257&lon=-3.817229681498797&units=metric: Error: Response error: 401 - Unauthorized Oct 11 13:42:09 volumio-ws5 volumio[1304]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:30:15) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async Promise.all (index 0) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:91:24) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:104:45) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:301:25 Oct 11 13:42:09 volumio-ws5 volumio[1304]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 11 13:42:09 volumio-ws5 volumio[1304]: error: [now-playing] Error fetching https://api.openweathermap.org/data/3.0/onecall?appid=7f653b8b2a3b367132e42fc3cf75756d&lat=53.28984268064257&lon=-3.817229681498797&units=metric: Error: Response error: 401 - Unauthorized Oct 11 13:42:09 volumio-ws5 volumio[1304]: at fetchPage (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:30:15) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async Promise.all (index 0) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:91:24) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async OpenWeatherMapAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:104:45) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:301:25 Oct 11 13:42:09 volumio-ws5 volumio[1304]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): Response error: 401 - Unauthorized Oct 11 13:42:09 volumio-ws5 volumio[1304]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Response error: 401 - Unauthorized Oct 11 13:42:09 volumio-ws5 volumio[1304]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:108:25) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 11 13:42:09 volumio-ws5 volumio[1304]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:126:28) Oct 11 13:42:09 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:42:09 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:42:12 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:12.773+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Oct 11 13:42:12 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 13:42:12 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 13:42:12 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 13:42:12 volumio-ws5 volumio[1304]: info: MyVolumio token set successfully Oct 11 13:42:12 volumio-ws5 volumio[1304]: info: MYVOLUMIO: Adding device Oct 11 13:42:12 volumio-ws5 volumio[1304]: info: MYVOLUMIO: Evaluating Server Oct 11 13:42:13 volumio-ws5 volumio[1304]: info: MyVolumio Plan changed: premium Oct 11 13:42:13 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 11 13:42:13 volumio-ws5 volumio[1304]: info: Removing browser output: myVolumio user plan is not superstar Oct 11 13:42:13 volumio-ws5 volumio[1304]: info: Removing audio output: Oct 11 13:42:13 volumio-ws5 volumio[1304]: info: MYVOLUMIO: Adding device Oct 11 13:42:13 volumio-ws5 volumio[1304]: info: MYVOLUMIO: Evaluating Server Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: Remote config written successfully Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: Starting Tunnel 1 Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: Starting Tunnel Connection Checker Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: Setting Geolocation for MyVolumio to eu3 Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: MYVolumio Device enabled Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: MyVolumio status changed Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: Streaming services startup Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: Starting Streaming Daemon Oct 11 13:42:14 volumio-ws5 sudo[2452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 11 13:42:14 volumio-ws5 sudo[2452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 13:42:14 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: Setting Geolocation for MyVolumio to eu3 Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:15 volumio-ws5 sudo[2452]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: Successfully Added MyVolumio device Oct 11 13:42:15 volumio-ws5 volumio[1304]: error: Cannot start Volumio Streaming Daemon Oct 11 13:42:15 volumio-ws5 volumio[1304]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 11 13:42:15 volumio-ws5 volumio[1304]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Oct 11 13:42:15 volumio-ws5 volumio[1304]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Oct 11 13:42:15 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 11 13:42:19 volumio-ws5 sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 11 13:42:19 volumio-ws5 sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:19 volumio-ws5 sudo[2457]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: MRS: MultiRoom plugin initialized Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: MRS: STOPPING SNAPCLIENT Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: MRS: Snap server stop Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: MRS: STOPPING volumioStreaming Oct 11 13:42:19 volumio-ws5 sudo[2474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 11 13:42:19 volumio-ws5 sudo[2474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:19 volumio-ws5 sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 11 13:42:19 volumio-ws5 sudo[2479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 11 13:42:19 volumio-ws5 sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:19 volumio-ws5 sudo[2474]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:19 volumio-ws5 sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:19 volumio-ws5 sudo[2481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 11 13:42:19 volumio-ws5 sudo[2481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:19 volumio-ws5 sudo[2481]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 11 13:42:19 volumio-ws5 sudo[2477]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:19 volumio-ws5 sudo[2479]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 11 13:42:19 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 11 13:42:20 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 11 13:42:20 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 11 13:42:20 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: Preparing to generate the ALSA configuration file Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: Updating MyVolumio device info Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: Updating MyVolumio device info Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: Reading ALSA contributions from plugins. Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: Successfully Added MyVolumio device Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: MRS: Removed streaming files Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: MRS: volumioStreaming STOPPED Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: MRS: SNAPSERVER STOPPED Oct 11 13:42:21 volumio-ws5 volumio[1304]: info: MRS: SNAPCLIENT STOPPED Oct 11 13:42:22 volumio-ws5 volumio[1304]: info: Successfully Updated MyVolumio device Oct 11 13:42:22 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:22.247+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=vyC09F0JxSbEA2fM8zdxYUo4ucu2 tokenExpiry=2026-10-11T14:42:22.247+01:00 Oct 11 13:42:22 volumio-ws5 volumio[1304]: info: Successfully Updated MyVolumio device Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: Received Get System Info Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: Discovery: Getting this device information Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 13:42:23 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:23.527+01:00 level=INFO msg="enabling local network discovery" Oct 11 13:42:23 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:23.559+01:00 level=INFO msg="enabling BLE discovery" Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: Asound.conf file written Oct 11 13:42:23 volumio-ws5 sudo[2511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 11 13:42:23 volumio-ws5 sudo[2511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:23 volumio-ws5 sudo[2511]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:23 volumio-ws5 volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 11 13:42:23 volumio-ws5 volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 11 13:42:23 volumio-ws5 volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 11 13:42:23 volumio-ws5 volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: Output device has changed, restarting MPD Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: Output device has changed, restarting Shairport Sync Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:23 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:24 volumio-ws5 sudo[2517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 13:42:24 volumio-ws5 sudo[2517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:24 volumio-ws5 sudo[2517]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:24 volumio-ws5 sudo[2522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: MRS: Pushing multiroomSync output for this device Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: MRS: Pushing multiroomSync output Oct 11 13:42:24 volumio-ws5 sudo[2522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Adding audio output: Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Adding audio output: Oct 11 13:42:24 volumio-ws5 systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:24 volumio-ws5 systemd[1]: mpd.service: Deactivated successfully. Oct 11 13:42:24 volumio-ws5 systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 11 13:42:24 volumio-ws5 systemd[1]: mpd.service: Consumed 1.668s CPU time. Oct 11 13:42:24 volumio-ws5 systemd[1]: mpd.socket: Deactivated successfully. Oct 11 13:42:24 volumio-ws5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 11 13:42:24 volumio-ws5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Preparing CD Folders Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Adding CD REST API Endpoints Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Starting UDEV Watcher for CD Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: Detecting CD presence with UDEV Oct 11 13:42:24 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 11 13:42:24 volumio-ws5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 11 13:42:24 volumio-ws5 systemd[1]: Starting mpd.service - Music Player Daemon... Oct 11 13:42:24 volumio-ws5 sudo[2532]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 13:42:24 volumio-ws5 sudo[2532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 11 13:42:24 volumio-ws5 sudo[2532]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:25 volumio-ws5 volumio5-onboarding[2382]: time=2026-10-11T13:42:25.246+01:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 11 13:42:25 volumio-ws5 mpd[2536]: 2026-10-11T13:42:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 13:42:26 volumio-ws5 systemd[1]: Started mpd.service - Music Player Daemon. Oct 11 13:42:26 volumio-ws5 sudo[2522]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:30 volumio-ws5 volumio[1304]: warn: [cd-plugin] cdspeedctl: device or media not ready Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 11 13:42:30 volumio-ws5 volumio[1304]: error: Hi Res Audio Failed Login: Missing Login Data Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: Adding TIDAL REST API Endpoints Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: Stopping AccessToken refresher cron for QOBUZ Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: AccessToken refresher cron started for QOBUZ Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: Adding QOBUZ REST API Endpoints Oct 11 13:42:30 volumio-ws5 volumio[1304]: error: error Oct 11 13:42:30 volumio-ws5 volumio[1304]: error: error Oct 11 13:42:30 volumio-ws5 volumio[1304]: error: error Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: MRS: Getting audio outputs on start Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: MRS: Requesting all other devices output Oct 11 13:42:30 volumio-ws5 volumio[1304]: info: MPD Permissions set Oct 11 13:42:31 volumio-ws5 volumio[1304]: error: updateQueue error: null Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 sudo[2542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 sudo[2542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: Starting Shairport Sync Oct 11 13:42:31 volumio-ws5 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 11 13:42:31 volumio-ws5 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 11 13:42:31 volumio-ws5 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Oct 11 13:42:31 volumio-ws5 sudo[2542]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:31 volumio-ws5 volumio[1304]: info: Remote SSH Started Oct 11 13:42:32 volumio-ws5 volumio[1304]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Oct 11 13:42:32 volumio-ws5 autossh[2552]: port set to 0, monitoring disabled Oct 11 13:42:32 volumio-ws5 autossh[2552]: starting ssh (count 1) Oct 11 13:42:32 volumio-ws5 autossh[2552]: ssh child pid is 2570 Oct 11 13:42:32 volumio-ws5 sudo[2567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 13:42:32 volumio-ws5 sudo[2567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 13:42:32 volumio-ws5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 11 13:42:32 volumio-ws5 systemd[1]: shairport-sync.service: Deactivated successfully. Oct 11 13:42:32 volumio-ws5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 13:42:32 volumio-ws5 systemd[1]: shairport-sync.service: Consumed 1.713s CPU time. Oct 11 13:42:32 volumio-ws5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 11 13:42:32 volumio-ws5 sudo[2567]: pam_unix(sudo:session): session closed for user root Oct 11 13:42:32 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:42:32 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:42:32 volumio-ws5 volumiossh-tunnel[2570]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Oct 11 13:42:32 volumio-ws5 volumio[1304]: info: Shairport-Sync Started Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:44 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:42:53 volumio-ws5 volumio[1304]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 11 13:43:11 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:43:11 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:43:16 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri Oct 11 13:43:19 volumio-ws5 volumio[1304]: info: Preload queue cleared Oct 11 13:43:24 volumio-ws5 volumio[1304]: error: error Oct 11 13:43:24 volumio-ws5 volumio[1304]: error: error Oct 11 13:43:24 volumio-ws5 volumio[1304]: error: error Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: Preload queue cleared Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::ClearQueue Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::stop Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::clearPlayQueue Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::saveQueue Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioPushQueue Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::addQueueItems Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::addQueueItems Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: Preload queue cleared Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D in service rp2 Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioPushQueue Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::saveQueue Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::updateTrackBlock Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrackBlock Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetState Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioPlay Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::play index 0 Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::stop Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::play index undefined Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreStateMachine::startPlaybackTimer Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CorePlayQueue::getTrack 0 Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 13:43:25 volumio-ws5 volumio[1304]: info: [rp2] clearAddPlayTrack: rp2/channel@id=0 Oct 11 13:43:26 volumio-ws5 volumio[1304]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=0&audio_type= Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] Obtained block for channel "0" Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] ------------- Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] Block summary Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] ------------- Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] Stream URL: https://audio.radioparadise.stream/audio/blocks/0/x/1383/4/b/1383-0.flac Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] Tracks: Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] 0. I'm Not The One (3:44 | elapsed: 23m 44s) Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] 1. This Is Hip (3:22 | elapsed: 27m 29s) Oct 11 13:43:26 volumio-ws5 volumio[1304]: info: [rp2] Oct 11 13:43:27 volumio-ws5 volumio[1304]: verbose: [rp2] Current track scheduled playback vs. current time: 10/11/2026, 1:41:40 PM <-> 10/11/2026, 1:43:26 PM Oct 11 13:43:27 volumio-ws5 volumio[1304]: info: [rp2] Going to start playback of current track at 1:46 (track position in stream: 23:44) Oct 11 13:43:27 volumio-ws5 volumio[1304]: info: [rp2] Starting mpv Oct 11 13:43:27 volumio-ws5 volumio[1304]: /bin/sh: 1: mpv: not found Oct 11 13:43:27 volumio-ws5 volumio[1304]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version Oct 11 13:43:27 volumio-ws5 volumio[1304]: /bin/sh: 1: mpv: not found Oct 11 13:43:27 volumio-ws5 volumio[1304]: info: [rp2] [mpv] mpv process spawned Oct 11 13:43:27 volumio-ws5 volumio[1304]: verbose: [rp2] Waiting for player event "playing"... Oct 11 13:43:27 volumio-ws5 volumio[1304]: info: [rp2] [mpv] (PID: 2662) /bin/sh: 1: mpv: not found Oct 11 13:43:27 volumio-ws5 volumio[1304]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:43:27 volumio-ws5 volumio[1304]: TypeError: Cannot read properties of null (reading 'reset') Oct 11 13:43:27 volumio-ws5 volumio[1304]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 Oct 11 13:43:27 volumio-ws5 volumio[1304]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) Oct 11 13:43:27 volumio-ws5 volumio[1304]: at ChildProcess.emit (node:events:514:28) Oct 11 13:43:27 volumio-ws5 volumio[1304]: at maybeClose (node:internal/child_process:1105:16) Oct 11 13:43:27 volumio-ws5 volumio[1304]: at Socket. (node:internal/child_process:457:11) Oct 11 13:43:27 volumio-ws5 volumio[1304]: at Socket.emit (node:events:514:28) Oct 11 13:43:27 volumio-ws5 volumio[1304]: at Pipe. (node:net:337:12) Oct 11 13:43:27 volumio-ws5 volumio[1304]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:43:30 volumio-ws5 sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 13:42' Oct 11 13:43:30 volumio-ws5 sudo[2678]: 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="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"