Mar 24 20:23:00 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:00 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:00 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:00 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:00 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:00 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:00 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:00 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:00 volumio volumio5-onboarding[5910]: time=2026-03-24T20:23:00.841+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:00 volumio volumio5-onboarding[5910]: time=2026-03-24T20:23:00.877+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:00 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:00 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:00 volumio volumio5-onboarding[5910]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:00 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:00 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:01 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27274. Mar 24 20:23:01 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:01 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.337+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:01 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:01 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:01 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:01 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.381+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.389+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:01 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:01 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:01 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:01 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:01 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:01 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.774+01: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" Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.776+01: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" Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.776+01: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" Mar 24 20:23:01 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:01.776+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" Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:02 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:02.875+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:02.875+01:00 Mar 24 20:23:03 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:03 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:03 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:03 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:03.709+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:03 volumio volumio5-onboarding[5943]: time=2026-03-24T20:23:03.738+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:03 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:03 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:03 volumio volumio5-onboarding[5943]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:03 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:03 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:03 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27275. Mar 24 20:23:03 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:04 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.087+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:04 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:04 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:04 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:04 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.132+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.141+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:04 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:04 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:04 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:04 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:04 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:04 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.502+01: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" Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.508+01: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" Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.508+01: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" Mar 24 20:23:04 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:04.508+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" Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:06 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:06.598+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:06.598+01:00 Mar 24 20:23:07 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:07 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:07 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:07 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:07.774+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:07 volumio volumio5-onboarding[5963]: time=2026-03-24T20:23:07.802+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:07 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:07 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:07 volumio volumio5-onboarding[5963]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:07 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:07 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:07 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27276. Mar 24 20:23:07 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:08 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.108+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:08 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:08 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:08 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:08 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.150+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.158+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:08 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:08 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:08 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:08 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:08 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:08 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.519+01: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" Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.526+01: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" Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.526+01: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" Mar 24 20:23:08 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:08.527+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" Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:09 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:09.605+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:09.605+01:00 Mar 24 20:23:10 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:10 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:10 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:10.481+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:10 volumio volumio5-onboarding[5984]: time=2026-03-24T20:23:10.509+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:10 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:10 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:10 volumio volumio5-onboarding[5984]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:10 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:10 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:10 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27277. Mar 24 20:23:10 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:10 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:10 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:10.888+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:10 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:10 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:10 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:10 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:10 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:10.945+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:10 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:10.955+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:10 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:10 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:10 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:10 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:10 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:10 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:11 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:11.347+01: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" Mar 24 20:23:11 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:11.349+01: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" Mar 24 20:23:11 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:11.350+01: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" Mar 24 20:23:11 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:11.350+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" Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:12 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:12.501+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:12.501+01:00 Mar 24 20:23:13 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:13 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:13 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:13.402+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:13 volumio volumio5-onboarding[6007]: time=2026-03-24T20:23:13.430+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:13 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:13 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:13 volumio volumio5-onboarding[6007]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:13 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:13 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:13 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27278. Mar 24 20:23:13 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:13 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:13 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:13.848+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:13 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:13 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:13 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:13 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:13 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:13.893+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:13 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:13.901+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:13 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:13 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:13 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:13 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:13 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:13 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:14 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:14.274+01: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" Mar 24 20:23:14 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:14.279+01: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" Mar 24 20:23:14 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:14.280+01: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" Mar 24 20:23:14 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:14.280+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" Mar 24 20:23:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:14 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:14 volumio volumio[15427]: info: Listing playlists Mar 24 20:23:14 volumio volumio[15427]: info: Listing playlists Mar 24 20:23:15 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:15.424+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:15.424+01:00 Mar 24 20:23:16 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:16 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:16 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:16 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:16 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:16 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:16 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:16 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:16 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:16.623+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:16 volumio volumio5-onboarding[6040]: time=2026-03-24T20:23:16.651+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:16 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:16 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:16 volumio volumio5-onboarding[6040]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:16 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:16 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:16 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27279. Mar 24 20:23:16 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:17 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.077+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:17 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:17 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:17 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:17 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.120+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.128+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:17 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:17 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:17 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:17 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:17 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:17 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.505+01: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" Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.507+01: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" Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.507+01: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" Mar 24 20:23:17 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:17.507+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" Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:17 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:18 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:18.946+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:18.946+01:00 Mar 24 20:23:19 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:19 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:19 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:19 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:19 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:19 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:19 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:19 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:19 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:19.848+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:19 volumio volumio5-onboarding[6060]: time=2026-03-24T20:23:19.875+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:19 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:19 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:19 volumio volumio5-onboarding[6060]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:19 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:19 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:20 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27280. Mar 24 20:23:20 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:20 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.347+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:20 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:20 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:20 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:20 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.391+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.399+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:20 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:20 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:20 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:20 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:20 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:20 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.787+01: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" Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.789+01: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" Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.789+01: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" Mar 24 20:23:20 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:20.789+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" Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:21 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:21.972+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:21.972+01:00 Mar 24 20:23:22 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:22 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:22 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:22 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:22 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:22 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:22 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:22 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:22 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:22.839+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:22 volumio volumio5-onboarding[6080]: time=2026-03-24T20:23:22.867+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:22 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:22 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:22 volumio volumio5-onboarding[6080]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:22 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:22 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:22 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 24 20:23:23 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27281. Mar 24 20:23:23 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:23 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.201+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:23 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:23 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:23 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:23 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.250+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.259+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:23 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:23 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:23 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:23 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:23 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:23 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.663+01: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" Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.666+01: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" Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.666+01: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" Mar 24 20:23:23 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:23.666+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" Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:24 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 24 20:23:24 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 24 20:23:24 volumio systemd[1]: setdatetime-helper.service: Consumed 1.136s CPU time. Mar 24 20:23:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:24 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:25 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:25.178+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:25.178+01:00 Mar 24 20:23:26 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:26 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:26 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:26.160+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:26 volumio volumio5-onboarding[6125]: time=2026-03-24T20:23:26.189+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:26 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:26 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:26 volumio volumio5-onboarding[6125]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:26 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:26 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:26 volumio volumio[15427]: verbose: New Socket.io Connection to 192.168.178.57:3000 from 192.168.178.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:26 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:26 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27282. Mar 24 20:23:26 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:26 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:26 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:26.598+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:26 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:26 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:26 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:26 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:26 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:26.640+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:26 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:26.648+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:26 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:26 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:26 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:26 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:26 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:26 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:27 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:27.429+01: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" Mar 24 20:23:27 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:27.433+01: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" Mar 24 20:23:27 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:27.433+01: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" Mar 24 20:23:27 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:27.434+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" Mar 24 20:23:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:28 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:28 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:28 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:28.934+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:28.934+01:00 Mar 24 20:23:29 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:29 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:29 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:29 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:29 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:29 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:29 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:29 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:29 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:29.796+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:29 volumio volumio5-onboarding[6167]: time=2026-03-24T20:23:29.823+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:29 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:29 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:29 volumio volumio5-onboarding[6167]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:29 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:29 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:29 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27283. Mar 24 20:23:29 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:30 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.097+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:30 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:30 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:30 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:30 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.140+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.149+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:30 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:30 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:30 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:30 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:30 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:30 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.510+01: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" Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.514+01: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" Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.515+01: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" Mar 24 20:23:30 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:30.515+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" Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:31 volumio volumio5-onboarding[6186]: time=2026-03-24T20:23:31.833+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:31.833+01:00 Mar 24 20:23:34 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:34 volumio volumio[15427]: info: Listing playlists Mar 24 20:23:34 volumio volumio[15427]: info: Listing playlists Mar 24 20:23:36 volumio volumio5-onboarding[6186]: failed to bootstrap state: failed to get logged user: could not get user info: failed to fetch user info: Get "https://myvolumio.firebaseio.com/users/P7KpvN2F7vOq55Zpl9vAN3i1Fmm1.json?auth=eyJhbGciOiJSUzI1NiIsImtpZCI6IjczMmNhOTY3MTNiMWRkMTcyMzg1MDg0Y2U5ZjQzODFhZDAwY2VjZTQiLCJ0eXAiOiJKV1QifQ.eyJpc3MiOiJodHRwczovL3NlY3VyZXRva2VuLmdvb2dsZS5jb20vbXl2b2x1bWlvIiwiYXVkIjoibXl2b2x1bWlvIiwiYXV0aF90aW1lIjoxNzc0MzgwMjExLCJ1c2VyX2lkIjoiUDdLcHZOMkY3dk9xNTVacGw5dkFOM2kxRm1tMSIsInN1YiI6IlA3S3B2TjJGN3ZPcTU1WnBsOXZBTjNpMUZtbTEiLCJpYXQiOjE3NzQzODAyMTEsImV4cCI6MTc3NDM4MzgxMSwiZW1haWwiOiJuYmVyZ2hzQGdtYWlsLmNvbSIsImVtYWlsX3ZlcmlmaWVkIjp0cnVlLCJmaXJlYmFzZSI6eyJpZGVudGl0aWVzIjp7ImVtYWlsIjpbIm5iZXJnaHNAZ21haWwuY29tIl19LCJzaWduX2luX3Byb3ZpZGVyIjoiY3VzdG9tIn19.12G1Qk8lfVq51Vo9SksxTUaagWF8wxHLkSIr7xtOXSKQvrLoahXm34xby6BK_UgYP_1WFBwVt0P87wPV-hDXH3-UdXGKW9Qj6OFKBOwLONkc_IigVrBR9Ye7dpWv0ti8NULzeb7mc2FETwlYlXdhZWXm34uiO_yPWsBFKfcDjVTxTAtN8up0NOyGkd-79-UyNZImgEMhxURHu-vx-ovAK4uJ6H2WOXSZ1VOZKGEVHIjCQFv-HjUsvJ0kSPF2lZjsm9cj3BlcoMRrDVXrT6CGwcZ4arFiD7oo0elrs3MIwVCaZnR5GgCoEoGgkdQR6Nxf166SK9w0GpyLcNp9Sd3dRw": EOF Mar 24 20:23:36 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:36 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:36 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27284. Mar 24 20:23:36 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:36 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:36 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:36.837+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:36 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:36 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:36 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:36 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:36 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:36.882+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:36 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:36.891+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:36 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:36 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:36 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:36 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:36 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:36 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:37 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:37.343+01: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" Mar 24 20:23:37 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:37.353+01: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" Mar 24 20:23:37 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:37.353+01: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" Mar 24 20:23:37 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:37.353+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" Mar 24 20:23:37 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:37 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:37 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:38 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:38 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:39 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:39.115+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:39.115+01:00 Mar 24 20:23:40 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:40 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:40 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:40.054+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:40 volumio volumio5-onboarding[6225]: time=2026-03-24T20:23:40.081+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:40 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:40 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:40 volumio volumio5-onboarding[6225]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:40 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:40 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:40 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27285. Mar 24 20:23:40 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:40 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:40 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:40.379+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:40 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:40 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:40 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:40 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:40 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:40.421+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:40 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:40.429+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:40 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:40 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:40 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:40 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:40 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:40 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:41 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:41.227+01: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" Mar 24 20:23:41 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:41.229+01: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" Mar 24 20:23:41 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:41.229+01: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" Mar 24 20:23:41 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:41.229+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" Mar 24 20:23:41 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:41 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:41 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:41 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:41 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:42 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:42.794+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:42.794+01:00 Mar 24 20:23:44 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:44 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:44 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:44.036+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:44 volumio volumio5-onboarding[6245]: time=2026-03-24T20:23:44.063+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:44 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:44 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:44 volumio volumio5-onboarding[6245]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:44 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:44 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:44 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27286. Mar 24 20:23:44 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:44 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:44 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:44.327+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:44 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:44 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:44 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:44 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:44 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:44.371+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:44 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:44.378+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:44 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:44 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:44 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:44 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:44 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:44 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:44 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:45 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:45.087+01: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" Mar 24 20:23:45 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:45.092+01: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" Mar 24 20:23:45 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:45.093+01: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" Mar 24 20:23:45 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:45.093+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" Mar 24 20:23:45 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:45 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:45 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:45 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:45 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:47 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:47.183+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:47.183+01:00 Mar 24 20:23:48 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:48 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:48 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:48.075+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:48 volumio volumio5-onboarding[6287]: time=2026-03-24T20:23:48.103+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:48 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:48 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:48 volumio volumio5-onboarding[6287]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:48 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:48 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:48 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27287. Mar 24 20:23:48 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:48 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.347+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:48 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:48 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:48 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:48 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.392+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.401+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:48 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:48 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:48 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:48 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:48 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:48 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.778+01: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" Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.785+01: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" Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.785+01: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" Mar 24 20:23:48 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:48.786+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" Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:48 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:49 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:49 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:50 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:50.541+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:50.541+01:00 Mar 24 20:23:51 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:51 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:51 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:51 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:51 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:51 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:51 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:51 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:51 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:51.689+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:51 volumio volumio5-onboarding[6309]: time=2026-03-24T20:23:51.717+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:51 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:51 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:51 volumio volumio5-onboarding[6309]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:51 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:51 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:51 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27288. Mar 24 20:23:51 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:52 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.097+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:52 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:52 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:52 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:52 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.140+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.148+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:52 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:52 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:52 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:52 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:52 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:52 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.561+01: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" Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.562+01: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" Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.562+01: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" Mar 24 20:23:52 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:52.563+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" Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:52 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:53 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:53.672+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:53.672+01:00 Mar 24 20:23:54 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:54 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:54 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:54.562+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:54 volumio volumio5-onboarding[6344]: time=2026-03-24T20:23:54.590+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:54 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:54 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:54 volumio volumio5-onboarding[6344]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:54 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:54 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:54 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27289. Mar 24 20:23:54 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:54 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:54 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:54.837+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:54 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:54 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:54 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:54 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:54 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:54.882+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:54 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:54.890+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:54 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:54 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:54 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:54 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:54 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:54 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:54 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:54 volumio volumio[15427]: info: Listing playlists Mar 24 20:23:54 volumio volumio[15427]: info: Listing playlists Mar 24 20:23:55 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:55.259+01: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" Mar 24 20:23:55 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:55.261+01: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" Mar 24 20:23:55 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:55.261+01: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" Mar 24 20:23:55 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:55.261+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" Mar 24 20:23:55 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:55 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:55 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:55 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:55 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:56 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:56.763+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:23:56.763+01:00 Mar 24 20:23:57 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:57 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:57 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:57 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:57 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:57 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:57 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:23:57 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:23:57 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:57.963+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:23:57 volumio volumio5-onboarding[6364]: time=2026-03-24T20:23:57.991+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:23:58 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:23:58 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:23:58 volumio volumio5-onboarding[6364]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:23:58 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:23:58 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:23:58 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27290. Mar 24 20:23:58 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:58 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.360+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:23:58 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:58 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:23:58 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:58 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.405+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.413+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:23:58 volumio volumio[15427]: info: Received Get System Info Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:23:58 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:23:58 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:23:58 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:23:58 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:23:58 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.814+01: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" Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.820+01: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" Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.820+01: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" Mar 24 20:23:58 volumio volumio5-onboarding[6392]: time=2026-03-24T20:23:58.821+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" Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:58 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:23:59 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:23:59 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:00 volumio volumio5-onboarding[6392]: time=2026-03-24T20:24:00.315+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:00.315+01:00 Mar 24 20:24:01 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:01 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:01 volumio volumio5-onboarding[6392]: time=2026-03-24T20:24:01.258+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:01 volumio volumio5-onboarding[6392]: time=2026-03-24T20:24:01.286+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:01 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:01 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:01 volumio volumio5-onboarding[6392]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:01 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:01 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:01 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27291. Mar 24 20:24:01 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:01 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:01 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:01.607+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:01 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:01 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:01 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:01 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:01 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:01.653+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:01 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:01.660+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:01 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:01 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:01 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:01 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:01 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:01 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:01 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:02 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:02.018+01: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" Mar 24 20:24:02 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:02.020+01: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" Mar 24 20:24:02 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:02.020+01: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" Mar 24 20:24:02 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:02.020+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" Mar 24 20:24:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:02 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:02 volumio volumio[15427]: info: Mar 24 20:24:02 volumio volumio[15427]: ---------------------------- MPD announces system playlist update Mar 24 20:24:02 volumio volumio[15427]: info: Ignoring MPD Status Update Mar 24 20:24:02 volumio volumio[15427]: info: Mar 24 20:24:02 volumio volumio[15427]: ---------------------------- MPD announces state update: player Mar 24 20:24:02 volumio volumio[15427]: info: ControllerMpd::getState Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:24:02 volumio volumio[15427]: info: Mar 24 20:24:02 volumio volumio[15427]: ---------------------------- MPD announces system playlist update Mar 24 20:24:02 volumio volumio[15427]: info: Ignoring MPD Status Update Mar 24 20:24:02 volumio volumio[15427]: info: Mar 24 20:24:02 volumio volumio[15427]: ---------------------------- MPD announces state update: player Mar 24 20:24:02 volumio volumio[15427]: info: ControllerMpd::getState Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:24:02 volumio volumio[15427]: info: Mar 24 20:24:02 volumio volumio[15427]: ---------------------------- MPD announces system playlist update Mar 24 20:24:02 volumio volumio[15427]: info: Ignoring MPD Status Update Mar 24 20:24:02 volumio volumio[15427]: info: Mar 24 20:24:02 volumio volumio[15427]: ---------------------------- MPD announces state update: player Mar 24 20:24:02 volumio volumio[15427]: info: ControllerMpd::getState Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:24:02 volumio volumio[15427]: info: ------------------------------ 9ms Mar 24 20:24:02 volumio volumio[15427]: info: sendMpdCommand status took 9 milliseconds Mar 24 20:24:02 volumio volumio[15427]: info: ------------------------------ 7ms Mar 24 20:24:02 volumio volumio[15427]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::parseState Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::parseState Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:24:02 volumio volumio[15427]: info: ------------------------------ 8ms Mar 24 20:24:02 volumio volumio[15427]: info: sendMpdCommand status took 7 milliseconds Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::parseState Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:24:02 volumio volumio[15427]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 20:24:02 volumio volumio[15427]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:24:03 volumio volumio[15427]: info: ControllerMpd::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::servicePushState Mar 24 20:24:03 volumio volumio[15427]: info: CorePlayQueue::getTrack 0 Mar 24 20:24:03 volumio volumio[15427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1348985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"NPO 3FM - 3FM Top Tijdloze Tracks - BNNVARA","artist":"NPO 3FM","album":null,"uri":"http://icecast.omroep.nl/3fm-bb-mp3","trackType":""} Mar 24 20:24:03 volumio volumio[15427]: verbose: CURRENT POSITION 0 Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::syncState stateService play Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:24:03 volumio volumio[15427]: info: Received an update from plugin. extracting info from payload Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:03 volumio volumio[15427]: info: ------------------------------ 32ms Mar 24 20:24:03 volumio volumio[15427]: info: sendMpdCommand playlistinfo took 21 milliseconds Mar 24 20:24:03 volumio volumio[15427]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 24 20:24:03 volumio volumio[15427]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:24:03 volumio volumio[15427]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:24:03 volumio volumio[15427]: info: ControllerMpd::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::servicePushState Mar 24 20:24:03 volumio volumio[15427]: info: CorePlayQueue::getTrack 0 Mar 24 20:24:03 volumio volumio[15427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1348985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"NPO 3FM - 3FM Top Tijdloze Tracks - BNNVARA","artist":"NPO 3FM","album":null,"uri":"http://icecast.omroep.nl/3fm-bb-mp3","trackType":""} Mar 24 20:24:03 volumio volumio[15427]: verbose: CURRENT POSITION 0 Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::syncState stateService play Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:24:03 volumio volumio[15427]: info: Received an update from plugin. extracting info from payload Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:03 volumio volumio[15427]: info: ControllerMpd::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::servicePushState Mar 24 20:24:03 volumio volumio[15427]: info: CorePlayQueue::getTrack 0 Mar 24 20:24:03 volumio volumio[15427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1348985,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"NPO 3FM - 3FM Top Tijdloze Tracks - BNNVARA","artist":"NPO 3FM","album":null,"uri":"http://icecast.omroep.nl/3fm-bb-mp3","trackType":""} Mar 24 20:24:03 volumio volumio[15427]: verbose: CURRENT POSITION 0 Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::syncState stateService play Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:24:03 volumio volumio[15427]: info: Received an update from plugin. extracting info from payload Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:03 volumio volumio[15427]: info: ------------------------------ 50ms Mar 24 20:24:03 volumio volumio[15427]: info: ------------------------------ 48ms Mar 24 20:24:03 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 20:24:03 volumio volumio[15427]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"NPO 3FM - 3FM Top Tijdloze Tracks - BNNVARA","artist":"NPO 3FM","album":"null","duration":"0","uri":"http://icecast.omroep.nl/3fm-bb-mp3","service":""} Mar 24 20:24:03 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:03.434+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:03.434+01:00 Mar 24 20:24:04 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:04 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:04 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:04.515+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:04 volumio volumio5-onboarding[6428]: time=2026-03-24T20:24:04.544+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:04 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:04 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:04 volumio volumio5-onboarding[6428]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:04 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:04 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:04 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27292. Mar 24 20:24:04 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:04 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:04 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:04.858+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:04 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:04 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:04 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:04 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:04 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:04.900+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:04 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:04.908+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:04 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:04 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:04 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:04 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:04 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:04 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:04 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:05 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:05.319+01: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" Mar 24 20:24:05 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:05.324+01: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" Mar 24 20:24:05 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:05.325+01: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" Mar 24 20:24:05 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:05.326+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" Mar 24 20:24:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:05 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:06 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:06.480+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:06.480+01:00 Mar 24 20:24:07 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:07 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:07 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:07.328+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:07 volumio volumio5-onboarding[6449]: time=2026-03-24T20:24:07.356+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:07 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:07 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:07 volumio volumio5-onboarding[6449]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:07 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:07 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:07 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27293. Mar 24 20:24:07 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:07 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:07 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:07.588+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:07 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:07 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:07 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:07 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:07 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:07.632+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:07 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:07.639+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:07 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:07 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:07 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:07 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:07 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:07 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:07 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:08 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:08.017+01: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" Mar 24 20:24:08 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:08.018+01: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" Mar 24 20:24:08 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:08.019+01: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" Mar 24 20:24:08 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:08.019+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" Mar 24 20:24:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:08 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:09 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:09.163+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:09.163+01:00 Mar 24 20:24:10 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:10 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:10 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:10.010+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:10 volumio volumio5-onboarding[6467]: time=2026-03-24T20:24:10.037+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:10 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:10 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:10 volumio volumio5-onboarding[6467]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:10 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:10 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:10 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27294. Mar 24 20:24:10 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:10 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:10 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:10.357+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:10 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:10 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:10 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:10 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:10 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:10.402+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:10 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:10.410+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:10 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:10 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:10 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:10 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:10 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:10 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:10 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:11 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:11.000+01: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" Mar 24 20:24:11 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:11.001+01: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" Mar 24 20:24:11 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:11.002+01: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" Mar 24 20:24:11 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:11.002+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" Mar 24 20:24:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:11 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:12 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:12.960+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:12.960+01:00 Mar 24 20:24:13 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:13 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:13 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:13 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:13 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:13.794+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:13 volumio volumio5-onboarding[6486]: time=2026-03-24T20:24:13.821+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:13 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:13 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:13 volumio volumio5-onboarding[6486]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:13 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:13 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:13 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27295. Mar 24 20:24:13 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:14 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.108+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:14 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:14 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:14 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:14 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.151+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.159+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:14 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:14 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:14 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:14 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:14 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:14 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.557+01: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" Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.559+01: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" Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.559+01: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" Mar 24 20:24:14 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:14.559+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" Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:14 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:14 volumio volumio[15427]: info: Listing playlists Mar 24 20:24:14 volumio volumio[15427]: info: Listing playlists Mar 24 20:24:15 volumio volumio5-onboarding[6529]: time=2026-03-24T20:24:15.674+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:15.674+01:00 Mar 24 20:24:20 volumio volumio[15427]: info: Mar 24 20:24:20 volumio volumio[15427]: ---------------------------- MPD announces system playlist update Mar 24 20:24:20 volumio volumio[15427]: info: Ignoring MPD Status Update Mar 24 20:24:20 volumio volumio[15427]: info: Mar 24 20:24:20 volumio volumio[15427]: ---------------------------- MPD announces state update: player Mar 24 20:24:20 volumio volumio[15427]: info: ControllerMpd::getState Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:24:20 volumio volumio[15427]: info: Mar 24 20:24:20 volumio volumio[15427]: ---------------------------- MPD announces system playlist update Mar 24 20:24:20 volumio volumio[15427]: info: Ignoring MPD Status Update Mar 24 20:24:20 volumio volumio[15427]: info: Mar 24 20:24:20 volumio volumio[15427]: ---------------------------- MPD announces state update: player Mar 24 20:24:20 volumio volumio[15427]: info: ControllerMpd::getState Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:24:20 volumio volumio[15427]: info: Mar 24 20:24:20 volumio volumio[15427]: ---------------------------- MPD announces system playlist update Mar 24 20:24:20 volumio volumio[15427]: info: Ignoring MPD Status Update Mar 24 20:24:20 volumio volumio[15427]: info: Mar 24 20:24:20 volumio volumio[15427]: ---------------------------- MPD announces state update: player Mar 24 20:24:20 volumio volumio[15427]: info: ControllerMpd::getState Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:24:20 volumio volumio[15427]: info: ------------------------------ 6ms Mar 24 20:24:20 volumio volumio[15427]: info: sendMpdCommand status took 5 milliseconds Mar 24 20:24:20 volumio volumio[15427]: info: ------------------------------ 4ms Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::parseState Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:24:20 volumio volumio[15427]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:24:20 volumio volumio[15427]: info: ------------------------------ 5ms Mar 24 20:24:20 volumio volumio[15427]: info: sendMpdCommand status took 3 milliseconds Mar 24 20:24:20 volumio volumio[15427]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::parseState Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::parseState Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:24:20 volumio volumio[15427]: info: ControllerMpd::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::servicePushState Mar 24 20:24:20 volumio volumio[15427]: info: CorePlayQueue::getTrack 0 Mar 24 20:24:20 volumio volumio[15427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1365986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Lady Gaga - Born This Way","artist":"NPO 3FM","album":null,"uri":"http://icecast.omroep.nl/3fm-bb-mp3","trackType":""} Mar 24 20:24:20 volumio volumio[15427]: verbose: CURRENT POSITION 0 Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::syncState stateService play Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:24:20 volumio volumio[15427]: info: Received an update from plugin. extracting info from payload Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:20 volumio volumio[15427]: info: ------------------------------ 23ms Mar 24 20:24:20 volumio volumio[15427]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 20:24:20 volumio volumio[15427]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:24:20 volumio volumio[15427]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:24:20 volumio volumio[15427]: info: ControllerMpd::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::servicePushState Mar 24 20:24:20 volumio volumio[15427]: info: CorePlayQueue::getTrack 0 Mar 24 20:24:20 volumio volumio[15427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1365986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Lady Gaga - Born This Way","artist":"NPO 3FM","album":null,"uri":"http://icecast.omroep.nl/3fm-bb-mp3","trackType":""} Mar 24 20:24:20 volumio volumio[15427]: verbose: CURRENT POSITION 0 Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::syncState stateService play Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:24:20 volumio volumio[15427]: info: Received an update from plugin. extracting info from payload Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:20 volumio volumio[15427]: info: ControllerMpd::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::servicePushState Mar 24 20:24:20 volumio volumio[15427]: info: CorePlayQueue::getTrack 0 Mar 24 20:24:20 volumio volumio[15427]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1365986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Lady Gaga - Born This Way","artist":"NPO 3FM","album":null,"uri":"http://icecast.omroep.nl/3fm-bb-mp3","trackType":""} Mar 24 20:24:20 volumio volumio[15427]: verbose: CURRENT POSITION 0 Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::syncState stateService play Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:24:20 volumio volumio[15427]: info: Received an update from plugin. extracting info from payload Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreStateMachine::pushState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioPushState Mar 24 20:24:20 volumio volumio[15427]: info: ------------------------------ 41ms Mar 24 20:24:20 volumio volumio[15427]: info: ------------------------------ 40ms Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 20:24:20 volumio volumio[15427]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Lady Gaga - Born This Way","artist":"NPO 3FM","album":"null","duration":"0","uri":"http://icecast.omroep.nl/3fm-bb-mp3","service":""} Mar 24 20:24:20 volumio volumio5-onboarding[6529]: failed to bootstrap state: failed to get logged user: could not get user info: failed to fetch user info: Get "https://myvolumio.firebaseio.com/users/P7KpvN2F7vOq55Zpl9vAN3i1Fmm1.json?auth=eyJhbGciOiJSUzI1NiIsImtpZCI6IjczMmNhOTY3MTNiMWRkMTcyMzg1MDg0Y2U5ZjQzODFhZDAwY2VjZTQiLCJ0eXAiOiJKV1QifQ.eyJpc3MiOiJodHRwczovL3NlY3VyZXRva2VuLmdvb2dsZS5jb20vbXl2b2x1bWlvIiwiYXVkIjoibXl2b2x1bWlvIiwiYXV0aF90aW1lIjoxNzc0MzgwMjU1LCJ1c2VyX2lkIjoiUDdLcHZOMkY3dk9xNTVacGw5dkFOM2kxRm1tMSIsInN1YiI6IlA3S3B2TjJGN3ZPcTU1WnBsOXZBTjNpMUZtbTEiLCJpYXQiOjE3NzQzODAyNTUsImV4cCI6MTc3NDM4Mzg1NSwiZW1haWwiOiJuYmVyZ2hzQGdtYWlsLmNvbSIsImVtYWlsX3ZlcmlmaWVkIjp0cnVlLCJmaXJlYmFzZSI6eyJpZGVudGl0aWVzIjp7ImVtYWlsIjpbIm5iZXJnaHNAZ21haWwuY29tIl19LCJzaWduX2luX3Byb3ZpZGVyIjoiY3VzdG9tIn19.t3-Xd922eNuiXCUfNB-IIk82YlpFoGJQuAjv9O10Rvf_6CCVxVijtXiPCXhaU_byaBxLTGJR5H7yEtd2RCSEMIOM-ThyZhwAdDDgNnYSeseKHY1NlsbdreCM2l0yu1hMAgvoG8jjCw9uCIDCIEIuyQVwgIQnTPeP3RpdvZhluMtE77mIGfFgSEWPX3HrM3OAi8TSefsmPRbrL5mGDNkU47snTcR4mV7aUw4P0BLkJ94POL5Bt4S0syOwLWYD6DliUVmNkitOpElzQaaXhAXk0y35lxzEOTNyMTiTEWUKrGCEJ0-lqUJwbXHe4g3YoaAEuiienEYuO4A4Ic5pqKLpnA": EOF Mar 24 20:24:20 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:20 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:20 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27296. Mar 24 20:24:20 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:20 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:20 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:20.598+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:20 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:20 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:20 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:20 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:20 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:20.640+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:20 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:20.648+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:20 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:20 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:20 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:20 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:20 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:20 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:20 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:21 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:21.463+01: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" Mar 24 20:24:21 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:21.468+01: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" Mar 24 20:24:21 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:21.469+01: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" Mar 24 20:24:21 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:21.469+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" Mar 24 20:24:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:21 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:22 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:22.683+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:22.683+01:00 Mar 24 20:24:23 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:23 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:23 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:23.567+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:23 volumio volumio5-onboarding[6553]: time=2026-03-24T20:24:23.597+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:23 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:23 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:23 volumio volumio5-onboarding[6553]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:23 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:23 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:23 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27297. Mar 24 20:24:23 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:23 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:23 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:23.849+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:23 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:23 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:23 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:23 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:23 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:23.940+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:23 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:23.949+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:23 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:23 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:23 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:23 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:23 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:23 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:23 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:24 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:24.363+01: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" Mar 24 20:24:24 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:24.364+01: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" Mar 24 20:24:24 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:24.365+01: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" Mar 24 20:24:24 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:24.365+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" Mar 24 20:24:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:24 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:24 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:25 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:25.963+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:25.963+01:00 Mar 24 20:24:26 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:26 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:26 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:26 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:26 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:26.874+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:26 volumio volumio5-onboarding[6586]: time=2026-03-24T20:24:26.903+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:26 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:26 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:26 volumio volumio5-onboarding[6586]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:26 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:26 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:27 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27298. Mar 24 20:24:27 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:27 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.348+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:27 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:27 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:27 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:27 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.391+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.399+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:27 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:27 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:27 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:27 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:27 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:27 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.775+01: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" Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.779+01: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" Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.779+01: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" Mar 24 20:24:27 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:27.780+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" Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:27 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:28 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:28 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:28 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:28.913+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:28.913+01:00 Mar 24 20:24:30 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:30 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:30 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:30.020+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:30 volumio volumio5-onboarding[6614]: time=2026-03-24T20:24:30.047+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:30 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:30 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:30 volumio volumio5-onboarding[6614]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:30 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:30 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:30 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27299. Mar 24 20:24:30 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:30 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.338+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:30 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:30 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:30 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:30 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.392+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.400+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:30 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:30 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:30 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:30 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:30 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:30 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.763+01: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" Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.765+01: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" Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.765+01: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" Mar 24 20:24:30 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:30.765+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" Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:30 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:31 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:31 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:31 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:31.954+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:31.953+01:00 Mar 24 20:24:32 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:32 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:32 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:32 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:32 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:32 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:32 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:32 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:32 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:32.813+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:32 volumio volumio5-onboarding[6634]: time=2026-03-24T20:24:32.840+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:32 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:32 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:32 volumio volumio5-onboarding[6634]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:32 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:32 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:32 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27300. Mar 24 20:24:32 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:33 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.088+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:33 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:33 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:33 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:33 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.133+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.141+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:33 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:33 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:33 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:33 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:33 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:33 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.506+01: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" Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.508+01: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" Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.508+01: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" Mar 24 20:24:33 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:33.508+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" Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:33 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:34 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:34 volumio volumio[15427]: info: Listing playlists Mar 24 20:24:34 volumio volumio[15427]: info: Listing playlists Mar 24 20:24:35 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:35.048+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:35.048+01:00 Mar 24 20:24:35 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:35 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:35 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:35 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:35 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:35 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:35 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:35 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:35 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:35.890+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:35 volumio volumio5-onboarding[6668]: time=2026-03-24T20:24:35.919+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:35 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:35 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:35 volumio volumio5-onboarding[6668]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:35 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:35 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:36 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27301. Mar 24 20:24:36 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:36 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.368+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:36 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:36 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:36 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:36 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.412+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.421+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:36 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:36 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:36 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:36 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:36 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:36 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.842+01: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" Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.843+01: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" Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.844+01: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" Mar 24 20:24:36 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:36.844+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" Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:36 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:37 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:37 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:38 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:38.396+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=P7KpvN2F7vOq55Zpl9vAN3i1Fmm1 tokenExpiry=2026-03-24T21:24:38.396+01:00 Mar 24 20:24:38 volumio volumio[15427]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/backup_restore/1.7.4/volumio/bookworm/armhf Mar 24 20:24:38 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:38.758+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:24:39 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:39 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:39 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:39 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:39 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:39 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:39 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:24:39 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:24:39 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:39.590+01:00 level=INFO msg="enabling local network discovery" Mar 24 20:24:39 volumio volumio5-onboarding[6687]: time=2026-03-24T20:24:39.621+01:00 level=INFO msg="enabling BLE discovery" Mar 24 20:24:39 volumio bluetoothd[986]: src/gatt-database.c:database_add_app() Failed to add service Mar 24 20:24:39 volumio bluetoothd[986]: src/gatt-database.c:client_ready_cb() Failed to create GATT service entry in local database Mar 24 20:24:39 volumio volumio5-onboarding[6687]: failed to configure discovery: failed to enable BLE discovery: failed to listen for BLE connections: failed to add service to adapter: Failed to create entry in database Mar 24 20:24:39 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 24 20:24:39 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 24 20:24:39 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 27302. Mar 24 20:24:39 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:40 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 24 20:24:40 volumio volumio5-onboarding[6711]: time=2026-03-24T20:24:40.098+01:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 24 20:24:40 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:40 volumio volumio[15427]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 20:24:40 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:40 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:40 volumio volumio5-onboarding[6711]: time=2026-03-24T20:24:40.149+01:00 level=INFO msg="system info for 31b21506adda7fc888cb941da7158129" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 24 20:24:40 volumio volumio5-onboarding[6711]: time=2026-03-24T20:24:40.157+01:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 24 20:24:40 volumio volumio[15427]: info: Received Get System Info Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:24:40 volumio volumio[15427]: info: Discovery: Getting this device information Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::volumioGetState Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:24:40 volumio volumio-remote-updater[705]: Test mode disabled Mar 24 20:24:40 volumio volumio-remote-updater[705]: Alpha mode disabled Mar 24 20:24:40 volumio volumio-remote-updater[705]: Alpha legacy test mode disabled Mar 24 20:24:40 volumio volumio[15427]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 20:24:40 volumio volumio[15427]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 24 20:24:40 volumio volumio[15427]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/backup_restore/1.7.4/volumio/bookworm/armhf Mar 24 20:24:40 volumio volumio5-onboarding[6711]: time=2026-03-24T20:24:40.213+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 20:24:40 volumio volumio[15427]: info: Folder /tmp/plugins removed Mar 24 20:24:40 volumio volumio[15427]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:24:40 volumio volumio[15427]: Error: Unable to resolve or reject the same promise twice Mar 24 20:24:40 volumio volumio[15427]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 24 20:24:40 volumio volumio[15427]: at /volumio/app/pluginmanager.js:1087:11 Mar 24 20:24:40 volumio volumio[15427]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Mar 24 20:24:40 volumio volumio[15427]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Mar 24 20:24:40 volumio volumio[15427]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Mar 24 20:24:40 volumio volumio[15427]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:107:14 Mar 24 20:24:40 volumio volumio[15427]: at FSReqCallback.oncomplete (node:fs:190:23) Mar 24 20:24:40 volumio volumio[15427]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:24:41 volumio sudo[6741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 20:23' Mar 24 20:24:41 volumio sudo[6741]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"