Oct 03 10:06:06 rivo-black volumio5-onboarding[14399]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 03 10:06:06 rivo-black systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 10:06:06 rivo-black systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 10:06:06 rivo-black systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 85. Oct 03 10:06:06 rivo-black systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 10:06:06 rivo-black systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 10:06:06 rivo-black volumio5-onboarding[14466]: time=2026-10-03T10:06:06.572+09:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 03 10:06:06 rivo-black volumio[3229]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:06:06 rivo-black volumio[3229]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:06:06 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:06:06 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:06:06 rivo-black volumio5-onboarding[14466]: time=2026-10-03T10:06:06.606+09:00 level=INFO msg="system info for cc5c1fbc058dd58e99c00388dd72941e" deviceName="Rivo Black" deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.208 Oct 03 10:06:06 rivo-black volumio5-onboarding[14466]: time=2026-10-03T10:06:06.612+09:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 03 10:06:06 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:06:06 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:06:06 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:06:06 rivo-black volumio-remote-updater[14410]: Test mode disabled Oct 03 10:06:06 rivo-black volumio-remote-updater[14410]: Alpha mode disabled Oct 03 10:06:06 rivo-black volumio-remote-updater[14410]: Alpha legacy test mode disabled Oct 03 10:06:06 rivo-black volumio-remote-updater[14410]: curl_easy_perform() failed: Couldn't resolve host name Oct 03 10:06:06 rivo-black volumio-remote-updater[14410]: terminate called after throwing an instance of 'std::invalid_argument' Oct 03 10:06:06 rivo-black volumio-remote-updater[14410]: what(): parse error - unexpected end of input Oct 03 10:06:06 rivo-black systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Oct 03 10:06:06 rivo-black systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Oct 03 10:06:06 rivo-black systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 85. Oct 03 10:06:06 rivo-black systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 03 10:06:06 rivo-black systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 03 10:06:06 rivo-black volumio-remote-updater[14488]: Volumio OTA Client: 1.7.0 Oct 03 10:06:06 rivo-black volumio-remote-updater[14488]: Error: No active session Oct 03 10:06:06 rivo-black volumio-remote-updater[14488]: [2026-10-03 10:06:06] [connect] Successful connection Oct 03 10:06:06 rivo-black volumio-remote-updater[14488]: [2026-10-03 10:06:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790989566 101 Oct 03 10:06:06 rivo-black volumio[3229]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:06:36 rivo-black volumio5-onboarding[14466]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 03 10:06:36 rivo-black systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 10:06:36 rivo-black systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 10:06:36 rivo-black systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 86. Oct 03 10:06:36 rivo-black systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 10:06:36 rivo-black systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 10:06:36 rivo-black volumio5-onboarding[14548]: time=2026-10-03T10:06:36.838+09:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 03 10:06:36 rivo-black volumio[3229]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:06:36 rivo-black volumio[3229]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:06:36 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:06:36 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:06:36 rivo-black volumio5-onboarding[14548]: time=2026-10-03T10:06:36.878+09:00 level=INFO msg="system info for cc5c1fbc058dd58e99c00388dd72941e" deviceName="Rivo Black" deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.208 Oct 03 10:06:36 rivo-black volumio5-onboarding[14548]: time=2026-10-03T10:06:36.884+09:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 03 10:06:36 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:06:36 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:06:36 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:06:36 rivo-black volumio-remote-updater[14488]: Test mode disabled Oct 03 10:06:36 rivo-black volumio-remote-updater[14488]: Alpha mode disabled Oct 03 10:06:36 rivo-black volumio-remote-updater[14488]: Alpha legacy test mode disabled Oct 03 10:06:36 rivo-black volumio-remote-updater[14488]: curl_easy_perform() failed: Couldn't resolve host name Oct 03 10:06:36 rivo-black volumio-remote-updater[14488]: terminate called after throwing an instance of 'std::invalid_argument' Oct 03 10:06:36 rivo-black volumio-remote-updater[14488]: what(): parse error - unexpected end of input Oct 03 10:06:36 rivo-black systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Oct 03 10:06:36 rivo-black systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Oct 03 10:06:37 rivo-black systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 86. Oct 03 10:06:37 rivo-black systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 03 10:06:37 rivo-black systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 03 10:06:37 rivo-black volumio-remote-updater[14565]: Volumio OTA Client: 1.7.0 Oct 03 10:06:37 rivo-black volumio-remote-updater[14565]: Error: No active session Oct 03 10:06:37 rivo-black volumio-remote-updater[14565]: [2026-10-03 10:06:37] [connect] Successful connection Oct 03 10:06:37 rivo-black volumio-remote-updater[14565]: [2026-10-03 10:06:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790989597 101 Oct 03 10:06:37 rivo-black volumio[3229]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:06:46 rivo-black dhcpcd[2993]: eth0: carrier lost Oct 03 10:06:46 rivo-black dhcpcd[2865]: eth0: carrier lost Oct 03 10:06:46 rivo-black kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Down Oct 03 10:06:46 rivo-black avahi-daemon[2922]: Withdrawing address record for 192.168.0.6 on eth0. Oct 03 10:06:46 rivo-black avahi-daemon[2922]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.6. Oct 03 10:06:46 rivo-black avahi-daemon[2922]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 03 10:06:46 rivo-black dhcpcd[2865]: eth0: deleting route to 192.168.0.0/24 Oct 03 10:06:46 rivo-black dhcpcd[2865]: eth0: deleting default route via 192.168.0.1 Oct 03 10:06:46 rivo-black dhcpcd[2993]: eth0: deleting route to 192.168.0.0/24 Oct 03 10:06:46 rivo-black dhcpcd[2993]: eth0: deleting default route via 192.168.0.1 Oct 03 10:06:46 rivo-black volumio[3229]: info: Discovery: A device disappeared from network Oct 03 10:06:46 rivo-black systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 10:06:46 rivo-black systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 03 10:06:46 rivo-black systemd[1]: welcome.service: Deactivated successfully. Oct 03 10:06:46 rivo-black systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 03 10:06:46 rivo-black systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 03 10:06:46 rivo-black systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 03 10:06:46 rivo-black welcome[14638]: Resolved ip:[0] Oct 03 10:06:46 rivo-black systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 03 10:06:46 rivo-black systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 10:06:46 rivo-black ifplugd(eth0)[3167]: Link beat lost. Oct 03 10:06:47 rivo-black ntpd[3139]: IO: Deleting interface #3 eth0, 192.168.0.6#123, interface stats: received=0, sent=0, dropped=0, active_time=2625 secs Oct 03 10:06:50 rivo-black volumio[3229]: info: Reporting MCU Network Status: 0 Oct 03 10:06:50 rivo-black volumio[3229]: info: Volumio Network Manager: Network status updated: 0 Oct 03 10:06:54 rivo-black kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 03 10:06:54 rivo-black dhcpcd[2993]: eth0: carrier acquired Oct 03 10:06:54 rivo-black dhcpcd[2865]: eth0: carrier acquired Oct 03 10:06:54 rivo-black dhcpcd[2993]: eth0: IAID 00:28:28:01 Oct 03 10:06:54 rivo-black dhcpcd[2865]: eth0: IAID 00:28:28:01 Oct 03 10:06:54 rivo-black dhcpcd[2865]: eth0: soliciting an IPv6 router Oct 03 10:06:54 rivo-black ifplugd(eth0)[3167]: Link beat detected. Oct 03 10:06:55 rivo-black dhcpcd[2993]: eth0: soliciting an IPv6 router Oct 03 10:06:55 rivo-black dhcpcd[2865]: eth0: rebinding lease of 192.168.0.6 Oct 03 10:06:55 rivo-black dhcpcd[2865]: eth0: probing address 192.168.0.6/24 Oct 03 10:06:55 rivo-black dhcpcd[2993]: eth0: rebinding lease of 192.168.0.6 Oct 03 10:06:55 rivo-black dhcpcd[2993]: eth0: probing address 192.168.0.6/24 Oct 03 10:07:00 rivo-black dhcpcd[2993]: eth0: leased 192.168.0.6 for 86400 seconds Oct 03 10:07:00 rivo-black avahi-daemon[2922]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.6. Oct 03 10:07:00 rivo-black avahi-daemon[2922]: New relevant interface eth0.IPv4 for mDNS. Oct 03 10:07:00 rivo-black avahi-daemon[2922]: Registering new address record for 192.168.0.6 on eth0.IPv4. Oct 03 10:07:00 rivo-black dhcpcd[2993]: eth0: adding route to 192.168.0.0/24 Oct 03 10:07:00 rivo-black dhcpcd[2993]: eth0: adding default route via 192.168.0.1 Oct 03 10:07:00 rivo-black systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 10:07:00 rivo-black systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 03 10:07:00 rivo-black systemd[1]: welcome.service: Deactivated successfully. Oct 03 10:07:00 rivo-black systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 03 10:07:00 rivo-black systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 03 10:07:00 rivo-black systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 03 10:07:00 rivo-black welcome[14690]: Resolved ip:[1] 192.168.0.6 Oct 03 10:07:00 rivo-black systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 03 10:07:00 rivo-black systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 10:07:00 rivo-black volumio[3229]: info: Reporting MCU Network Status: 1 Oct 03 10:07:00 rivo-black volumio[3229]: info: Volumio Network Manager: Network status updated: 1 Oct 03 10:07:01 rivo-black dhcpcd[2865]: eth0: leased 192.168.0.6 for 86400 seconds Oct 03 10:07:01 rivo-black dhcpcd[2865]: eth0: adding route to 192.168.0.0/24 Oct 03 10:07:01 rivo-black dhcpcd[2865]: eth0: adding default route via 192.168.0.1 Oct 03 10:07:01 rivo-black systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 10:07:01 rivo-black systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 03 10:07:01 rivo-black systemd[1]: welcome.service: Deactivated successfully. Oct 03 10:07:01 rivo-black systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 03 10:07:01 rivo-black systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 03 10:07:01 rivo-black systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 03 10:07:01 rivo-black welcome[14745]: Resolved ip:[1] 192.168.0.6 Oct 03 10:07:01 rivo-black systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 03 10:07:01 rivo-black systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 10:07:01 rivo-black volumio[3229]: info: Discovery: adding 293cb388-bb46-44a0-93a3-40a879bddf0e Oct 03 10:07:01 rivo-black volumio[3229]: info: Discovery: Found device Rivo Black Oct 03 10:07:01 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:07:02 rivo-black ntpd[3139]: IO: Listen normally on 4 eth0 192.168.0.6:123 Oct 03 10:07:02 rivo-black ntpd[3139]: IO: new interface(s) found: waking up resolver Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: Pool taking: 109.61.109.140 Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: Pool taking: 129.250.35.251 Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: Pool taking: 160.251.138.123 Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: Pool taking: 133.18.147.168 Oct 03 10:07:02 rivo-black ntpd[3139]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool skipping: 129.250.35.251 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 156.246.90.124 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 139.162.81.45 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 172.104.124.149 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 2606:4700:f1::123 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 2001:418:3ff::1:53 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 2406:da14:10c4:9aa0:123:: Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: Pool taking: 2400:8902::2000:12ff:fecd:605 Oct 03 10:07:03 rivo-black ntpd[3139]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 03 10:07:04 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: Pool taking: 162.159.200.123 Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: Pool skipping: 133.18.147.168 Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: Pool skipping: 129.250.35.251 Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: Pool taking: 140.245.90.15 Oct 03 10:07:04 rivo-black ntpd[3139]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: Pool taking: 172.233.91.137 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: Pool taking: 162.159.200.1 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: Pool taking: 142.91.108.61 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: Pool taking: 167.179.119.205 Oct 03 10:07:05 rivo-black ntpd[3139]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 03 10:07:05 rivo-black volumio[3229]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 03 10:07:05 rivo-black volumio[3229]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 03 10:07:05 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 10:07:05 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 10:07:05 rivo-black volumio[3229]: info: Starting MyVolumio Remote Streaming Endpoints Oct 03 10:07:05 rivo-black volumio[3229]: info: MyVolumio login type: Token Oct 03 10:07:05 rivo-black volumio[3229]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 03 10:07:05 rivo-black volumio[3229]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Oct 03 10:07:06 rivo-black volumio[3229]: error: MyVolumio Custom Token format not valid, refreshing it Oct 03 10:07:06 rivo-black volumio5-onboarding[14548]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Oct 03 10:07:06 rivo-black systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 10:07:06 rivo-black systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 10:07:07 rivo-black systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 87. Oct 03 10:07:07 rivo-black systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 10:07:07 rivo-black systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 10:07:07 rivo-black volumio5-onboarding[14759]: time=2026-10-03T10:07:07.076+09:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 03 10:07:07 rivo-black volumio[3229]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:07:07 rivo-black volumio[3229]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 03 10:07:07 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:07:07 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:07:07 rivo-black volumio5-onboarding[14759]: time=2026-10-03T10:07:07.112+09:00 level=INFO msg="system info for cc5c1fbc058dd58e99c00388dd72941e" deviceName="Rivo Black" deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.208 Oct 03 10:07:07 rivo-black volumio5-onboarding[14759]: time=2026-10-03T10:07:07.118+09:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 03 10:07:07 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:07:07 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:07:07 rivo-black volumio-remote-updater[14565]: Test mode disabled Oct 03 10:07:07 rivo-black volumio-remote-updater[14565]: Alpha mode disabled Oct 03 10:07:07 rivo-black volumio-remote-updater[14565]: Alpha legacy test mode disabled Oct 03 10:07:07 rivo-black volumio[3229]: info: MyVolumio login type: Token Oct 03 10:07:07 rivo-black volumio[3229]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 10:07:07 rivo-black volumio[3229]: info: Received Get System Info Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 10:07:07 rivo-black volumio[3229]: info: Discovery: Getting this device information Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 10:07:07 rivo-black volumio[3229]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 10:07:07 rivo-black volumio5-onboarding[14759]: time=2026-10-03T10:07:07.754+09:00 level=INFO msg="enabling local network discovery" Oct 03 10:07:07 rivo-black volumio5-onboarding[14759]: time=2026-10-03T10:07:07.755+09:00 level=INFO msg="enabling BLE discovery" Oct 03 10:07:07 rivo-black volumio[3229]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 03 10:07:08 rivo-black volumio[3229]: info: MyVolumio token set successfully Oct 03 10:07:08 rivo-black volumio[3229]: info: MYVOLUMIO: Adding device Oct 03 10:07:08 rivo-black volumio[3229]: info: MYVOLUMIO: Evaluating Server Oct 03 10:07:08 rivo-black volumio[3229]: info: MyVolumio status changed Oct 03 10:07:08 rivo-black volumio[3229]: info: Streaming services startup Oct 03 10:07:08 rivo-black volumio[3229]: info: Starting Streaming Daemon Oct 03 10:07:08 rivo-black volumio[3229]: info: Removing browser output: myVolumio user plan is not superstar Oct 03 10:07:08 rivo-black volumio[3229]: info: Removing audio output: Oct 03 10:07:08 rivo-black volumio[3229]: info: Stoppping Tunnel 1 Oct 03 10:07:08 rivo-black sudo[14800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 03 10:07:08 rivo-black sudo[14800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 10:07:08 rivo-black ntpd[3139]: CLOCK: time stepped by -0.563815 Oct 03 10:07:08 rivo-black systemd-journald[2586]: Time jumped backwards, rotating. Oct 03 10:07:08 rivo-black ntpd[3139]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 03 10:07:08 rivo-black sudo[14802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 03 10:07:08 rivo-black sudo[14802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 10:07:08 rivo-black volumio[3229]: error: Failed to ping endpoint as2.myvolumio.org : unknown error Oct 03 10:07:08 rivo-black volumio[3229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 10:07:08 rivo-black volumio[3229]: Error: Unable to resolve or reject the same promise twice Oct 03 10:07:08 rivo-black volumio[3229]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 03 10:07:08 rivo-black volumio[3229]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Oct 03 10:07:08 rivo-black volumio[3229]: at Socket.emit (node:events:514:28) Oct 03 10:07:08 rivo-black volumio[3229]: at addChunk (node:internal/streams/readable:343:12) Oct 03 10:07:08 rivo-black volumio[3229]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 03 10:07:08 rivo-black volumio[3229]: at Readable.push (node:internal/streams/readable:253:10) Oct 03 10:07:08 rivo-black volumio[3229]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 03 10:07:08 rivo-black volumio[3229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 10:07:08 rivo-black sudo[14800]: pam_unix(sudo:session): session closed for user root Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 10:07:08 rivo-black sudo[14802]: pam_unix(sudo:session): session closed for user root Oct 03 10:07:08 rivo-black volumio5-onboarding[14759]: time=2026-10-03T10:07:08.797+09:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 03 10:07:08 rivo-black sudo[14815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 10:06' Oct 03 10:07:08 rivo-black sudo[14815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"