-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2026-08-27 10:42:21 CEST. -- Aug 27 10:41:02 zolder volumio[893]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 27 10:41:03 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:03 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:03 zolder systemd[1]: systemd-fsckd.service: Succeeded. Aug 27 10:41:04 zolder volumio-remote-updater[613]: [2026-08-27 10:41:04] [connect] Successful connection Aug 27 10:41:04 zolder volumio-remote-updater[613]: [2026-08-27 10:41:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1787820064 101 Aug 27 10:41:04 zolder volumio[893]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Aug 27 10:41:04 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:04 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:04 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:04 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:04 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 10:41:08 zolder volumio[893]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 10:41:09 zolder volumio[893]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 10:41:09 zolder volumio[893]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 10:41:09 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:09 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:09 zolder volumio[893]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 10:41:09 zolder volumio[893]: info: MyVolumio login type: Token Aug 27 10:41:09 zolder volumio[893]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 10:41:09 zolder volumio[893]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 27 10:41:11 zolder volumio[893]: info: Starting Streaming Service Transparent Proxy Aug 27 10:41:11 zolder volumio[893]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 27 10:41:11 zolder volumio[893]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 27 10:41:11 zolder volumio[893]: info: Streaming services startup Aug 27 10:41:11 zolder volumio[893]: info: Starting Streaming Daemon Aug 27 10:41:11 zolder sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 10:41:11 zolder sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 10:41:11 zolder volumio[893]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 27 10:41:11 zolder sudo[1265]: pam_unix(sudo:session): session closed for user root Aug 27 10:41:11 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:11 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:11 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:11 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:11 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:11 zolder volumio[893]: error: Cannot start Volumio Streaming Daemon Aug 27 10:41:11 zolder volumio[893]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 10:41:11 zolder volumio[893]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 10:41:11 zolder volumio[893]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 27 10:41:11 zolder volumio[893]: error: MyVolumio Custom Token format not valid, refreshing it Aug 27 10:41:12 zolder volumio[893]: info: MyVolumio login type: Token Aug 27 10:41:12 zolder volumio[893]: STREAMING PROXY: Starting server on port 3245 Aug 27 10:41:12 zolder volumio[893]: Node JS runtime: 14 Aug 27 10:41:12 zolder systemd[1]: systemd-timedated.service: Succeeded. Aug 27 10:41:12 zolder volumio[893]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 27 10:41:13 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:13 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:13 zolder volumio[893]: info: Listing playlists Aug 27 10:41:13 zolder volumio[893]: info: Listing playlists Aug 27 10:41:14 zolder volumio[893]: info: MyVolumio token set successfully Aug 27 10:41:14 zolder volumio[893]: info: MYVOLUMIO: Adding device Aug 27 10:41:14 zolder volumio[893]: info: MYVOLUMIO: Evaluating Server Aug 27 10:41:14 zolder volumio[893]: info: MyVolumio status changed Aug 27 10:41:14 zolder volumio[893]: info: Streaming services startup Aug 27 10:41:14 zolder volumio[893]: info: Starting Streaming Daemon Aug 27 10:41:15 zolder volumio[893]: info: Removing browser output: myVolumio user plan is not superstar Aug 27 10:41:15 zolder volumio[893]: info: Removing audio output: Aug 27 10:41:15 zolder volumio[893]: info: Stoppping Tunnel 1 Aug 27 10:41:15 zolder sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 10:41:15 zolder sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 10:41:15 zolder sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 27 10:41:15 zolder sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 10:41:15 zolder sudo[1295]: pam_unix(sudo:session): session closed for user root Aug 27 10:41:15 zolder volumio[893]: error: Cannot start Volumio Streaming Daemon Aug 27 10:41:15 zolder volumio[893]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 10:41:15 zolder volumio[893]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 10:41:15 zolder sudo[1298]: pam_unix(sudo:session): session closed for user root Aug 27 10:41:15 zolder volumio[893]: info: Remote SSH Stopped Aug 27 10:41:15 zolder volumio[893]: info: Setting Geolocation for MyVolumio to eu2 Aug 27 10:41:15 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:15 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:15 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:15 zolder volumio[893]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 27 10:41:16 zolder volumio[893]: info: Updating MyVolumio device info Aug 27 10:41:16 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:16 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:16 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:16 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:16 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:16 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:17 zolder volumio[893]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 27 10:41:18 zolder volumio[893]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 27 10:41:19 zolder volumio[893]: info: MYVOLUMIO: Adding device Aug 27 10:41:19 zolder volumio[893]: info: MYVOLUMIO: Evaluating Server Aug 27 10:41:20 zolder volumio[893]: info: Setting Geolocation for MyVolumio to eu2 Aug 27 10:41:20 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:20 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:20 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:20 zolder volumio[893]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 27 10:41:21 zolder volumio[893]: info: Updating MyVolumio device info Aug 27 10:41:21 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:21 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:21 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:21 zolder volumio[893]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 27 10:41:21 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:21 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:21 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:21 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:21 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:23 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:23 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:24 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 10:41:24 zolder volumio[893]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 10:41:24 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:24 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:26 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:26 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:26 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:26 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:26 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 10:41:28 zolder volumio-remote-updater[613]: Test mode enabled Aug 27 10:41:28 zolder volumio-remote-updater[613]: No alpha test mode Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 27 10:41:28 zolder volumio[893]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:28 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 10:41:29 zolder volumio5-onboarding[1229]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Aug 27 10:41:29 zolder systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 10:41:29 zolder systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 10:41:29 zolder systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Aug 27 10:41:29 zolder systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 27 10:41:29 zolder systemd[1]: Stopped Volumio5 Onboarding Server. Aug 27 10:41:29 zolder systemd[1]: Started Volumio5 Onboarding Server. Aug 27 10:41:29 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:29.299+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Aug 27 10:41:29 zolder volumio[893]: 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: 4 Aug 27 10:41:29 zolder volumio[893]: 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: 4 Aug 27 10:41:29 zolder volumio[893]: info: Received Get System Info Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:41:29 zolder volumio[893]: info: Discovery: Getting this device information Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:29 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:41:29 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:29.340+02:00 level=INFO msg="system info for a5d1b359b3cb9e7c1a2c11c087a081f9" deviceName=Zolder deviceVariant=volumio deviceModel= softwareVersion=3.914 Aug 27 10:41:29 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:29.410+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 10:41:29 zolder volumio[893]: info: Received Get System Info Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:41:29 zolder volumio[893]: info: Discovery: Getting this device information Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:29 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:41:29 zolder volumio-remote-updater[613]: Test mode enabled Aug 27 10:41:29 zolder volumio-remote-updater[613]: No alpha test mode Aug 27 10:41:29 zolder volumio[893]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 10:41:29 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 10:41:30 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:30.384+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Aug 27 10:41:30 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:30.387+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Aug 27 10:41:30 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:30.387+02:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Aug 27 10:41:30 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:30.387+02: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" Aug 27 10:41:30 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:30 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:30 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 10:41:31 zolder volumio[893]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 27 10:41:31 zolder volumio[893]: info: Completed starting MyVolumio Plugin Aug 27 10:41:31 zolder volumio[893]: [Metrics] CommandRouter: 47s 935.33ms Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::Close All Modals sent Aug 27 10:41:31 zolder volumio[893]: info: CoreCommandRouter::Close All Modals sent Aug 27 10:41:31 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:31 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:31 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:31 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:31 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:32 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:32.296+02:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=kSJpvNAtUaTt2wPkNbk2G1vOwpG2 tokenExpiry=2026-08-27T11:41:32.296+02:00 Aug 27 10:41:32 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 10:41:32 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 10:41:32 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 10:41:33 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:33 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:33 zolder volumio[893]: info: Listing playlists Aug 27 10:41:33 zolder volumio[893]: info: Listing playlists Aug 27 10:41:33 zolder volumio[893]: info: Received Get System Info Aug 27 10:41:33 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:41:33 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:41:33 zolder volumio[893]: info: Discovery: Getting this device information Aug 27 10:41:33 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:33 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:33 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:41:33 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 10:41:33 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:33.350+02:00 level=INFO msg="enabling local network discovery" Aug 27 10:41:33 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:33.366+02:00 level=INFO msg="enabling BLE discovery" Aug 27 10:41:34 zolder volumio5-onboarding[1349]: time=2026-08-27T10:41:34.377+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 10:41:36 zolder volumio[893]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 27 10:41:36 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:36 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:36 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:36 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:36 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:38 zolder volumio[893]: info: BOOT COMPLETED Aug 27 10:41:41 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:41 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:41 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:41 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:41 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:43 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:43 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:46 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:46 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:46 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:46 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:46 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:51 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:51 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:51 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:51 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:51 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:41:53 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:41:53 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:41:53 zolder volumio[893]: info: Listing playlists Aug 27 10:41:53 zolder volumio[893]: info: Listing playlists Aug 27 10:41:56 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:41:56 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:41:56 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:41:56 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:41:56 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:42:01 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:42:01 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:42:01 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:42:01 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:42:01 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:42:03 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:03 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:06 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:42:06 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:42:06 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:42:06 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:42:06 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:42:10 zolder sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 10:42:10 zolder sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 10:42:10 zolder sudo[1457]: pam_unix(sudo:session): session closed for user root Aug 27 10:42:10 zolder sudo[1459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 10:42:10 zolder sudo[1459]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 27 10:42:10 zolder sudo[1459]: pam_unix(sudo:session): session closed for user root Aug 27 10:42:10 zolder volumio[893]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 27 10:42:10 zolder volumio[893]: verbose: New Socket.io Connection to 192.168.5.3 from 192.168.1.217 UA: Mozilla/5.0 (X11; Linux x86_64; rv:154.0) Gecko/20100101 Firefox/154.0 Engine version: 3 Transport: polling Total Clients: 5 Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:10 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::volumioGetQueue Aug 27 10:42:10 zolder volumio[893]: info: CoreStateMachine::getQueue Aug 27 10:42:10 zolder volumio[893]: info: CorePlayQueue::getQueue Aug 27 10:42:10 zolder volumio[893]: info: Listing playlists Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 10:42:10 zolder volumio[893]: info: Received Get System Info Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:42:10 zolder volumio[893]: info: Discovery: Getting this device information Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:10 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:10 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 10:42:10 zolder volumio[893]: error: Cannot get UI Settings Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 10:42:10 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 27 10:42:11 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:42:11 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:42:11 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:42:11 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:42:11 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:42:11 zolder volumio[893]: Searching plugin music_service/jellyfin Aug 27 10:42:11 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: jellyfin , search Aug 27 10:42:11 zolder volumio[893]: info: [jellyfin-conn] Using previously assigned device Id for stefan@jellyfin: 8112cab1-31eb-47eb-8ec8-eed654b0c3da Aug 27 10:42:11 zolder volumio[893]: info: [jellyfin-conn] Returning existing auth promise Aug 27 10:42:11 zolder volumio[893]: info: [jellyfin-conn] Returning existing auth promise Aug 27 10:42:12 zolder volumio[893]: info: [jellyfin-conn] Login successful: stefan@jellyfin Aug 27 10:42:12 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:42:12 zolder volumio[893]: info: Received Get System Info Aug 27 10:42:12 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:42:12 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:42:12 zolder volumio[893]: info: Discovery: Getting this device information Aug 27 10:42:12 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:12 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:12 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:42:13 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:13 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:13 zolder volumio[893]: info: Listing playlists Aug 27 10:42:13 zolder volumio[893]: info: Listing playlists Aug 27 10:42:13 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 10:42:13 zolder volumio[893]: info: Received Get System Info Aug 27 10:42:13 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 10:42:13 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 10:42:13 zolder volumio[893]: info: Discovery: Getting this device information Aug 27 10:42:13 zolder volumio[893]: info: CoreCommandRouter::volumioGetState Aug 27 10:42:13 zolder volumio[893]: info: CorePlayQueue::getTrack 0 Aug 27 10:42:13 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 10:42:16 zolder volumio[893]: error: [ytcr] Error connecting MPD: Aug 27 10:42:16 zolder volumio[893]: (Error) connect ENOENT /run/mpd/socket Aug 27 10:42:16 zolder volumio[893]: Error: connect ENOENT /run/mpd/socket Aug 27 10:42:16 zolder volumio[893]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 27 10:42:16 zolder volumio[893]: Retrying in 5 seconds... Aug 27 10:42:17 zolder volumio[893]: info: All search sources collected, pushing search results Aug 27 10:42:20 zolder volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 27 10:42:20 zolder volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:42:20 zolder volumio[893]: TypeError: Cannot read property 'then' of undefined Aug 27 10:42:20 zolder volumio[893]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:2027:20) Aug 27 10:42:20 zolder volumio[893]: at Socket.emit (events.js:315:20) Aug 27 10:42:20 zolder volumio[893]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 27 10:42:20 zolder volumio[893]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 27 10:42:20 zolder volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 10:42:21 zolder sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-27 10:41 Aug 27 10:42:21 zolder sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5c0e72e4b5817fe66c91c9d15d75ff13b8e3f4ba" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Mar 2026 02:51:29 PM CEST" VOLUMIO_VERSION="3.914" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="98fe12c88d8ff391f569279e2f874892"