Mar 27 19:51:03 volumio volumio[1128]: info: The following additional packages will be installed: Mar 27 19:51:03 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:03.816+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:03 volumio volumio[1128]: info: chromium chromium-common chromium-l10n chromium-sandbox Mar 27 19:51:03 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:03.903+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:03 volumio volumio[1128]: info: Suggested packages: Mar 27 19:51:03 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:03.961+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:03 volumio volumio[1128]: info: chromium-shell chromium-driver Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.015+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:04 volumio volumio[1128]: info: The following packages will be upgraded: Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.428+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:04 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:04 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:04 volumio volumio[1128]: info: Listing playlists Mar 27 19:51:04 volumio volumio[1128]: info: Listing playlists Mar 27 19:51:04 volumio volumio[1128]: info: chromium chromium-browser chromium-common chromium-l10n chromium-sandbox Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.569+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:04 volumio volumio[1128]: info: 5 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.837+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:04 volumio volumio[1128]: info: Need to get 153 MB of archives. Mar 27 19:51:04 volumio volumio[1128]: info: After this operation, 12.3 kB disk space will be freed. Mar 27 19:51:04 volumio volumio[1128]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-l10n all 1:146.0.7680.164-1~deb12u1+rpt1 [20.0 MB] Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.937+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.967+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:04 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:04.968+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:08 volumio dhcpcd[836]: eth0: failed to renew DHCP, rebinding Mar 27 19:51:08 volumio dhcpcd[836]: eth0: NAK: address not available from 192.168.62.1 Mar 27 19:51:08 volumio dhcpcd[836]: eth0: message: address not available Mar 27 19:51:08 volumio avahi-daemon[784]: Withdrawing address record for 192.168.62.25 on eth0. Mar 27 19:51:08 volumio avahi-daemon[784]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.62.25. Mar 27 19:51:08 volumio avahi-daemon[784]: Interface eth0.IPv4 no longer relevant for mDNS. Mar 27 19:51:08 volumio dhcpcd[836]: eth0: deleting route to 192.168.62.0/24 Mar 27 19:51:08 volumio dhcpcd[836]: eth0: deleting default route via 192.168.62.4 Mar 27 19:51:08 volumio dhcpcd[736]: eth0: pid 836 deleted IP address 192.168.62.25/24 Mar 27 19:51:08 volumio dhcpcd[736]: eth0: deleting route to 192.168.62.0/24 Mar 27 19:51:08 volumio dhcpcd[736]: eth0: deleting default route via 192.168.62.4 Mar 27 19:51:08 volumio volumio[1128]: info: Discovery: A device disappeared from network Mar 27 19:51:08 volumio dhcpcd[836]: eth0: soliciting a DHCP lease Mar 27 19:51:08 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:51:08 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 27 19:51:08 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 27 19:51:08 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 27 19:51:08 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 27 19:51:08 volumio dhcpcd[736]: eth0: soliciting a DHCP lease Mar 27 19:51:08 volumio dhcpcd[836]: eth0: offered 192.168.62.25 from 192.168.62.4 Mar 27 19:51:08 volumio dhcpcd[836]: eth0: probing address 192.168.62.25/24 Mar 27 19:51:08 volumio dhcpcd[736]: eth0: offered 192.168.62.25 from 192.168.62.4 Mar 27 19:51:08 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 27 19:51:08 volumio dhcpcd[736]: eth0: probing address 192.168.62.25/24 Mar 27 19:51:08 volumio welcome[2645]: Resolved ip:[0] Mar 27 19:51:08 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 27 19:51:08 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:51:08 volumio volumio[1128]: info: Received Get System Info Mar 27 19:51:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:51:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:51:08 volumio volumio[1128]: info: Discovery: Getting this device information Mar 27 19:51:08 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:08 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:51:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 19:51:08 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 19:51:09 volumio ntpd[1013]: IO: Deleting interface #4 eth0, 192.168.62.25#123, interface stats: received=24, sent=24, dropped=0, active_time=104 secs Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 195.85.215.8 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 87.120.244.14 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 2.56.14.18 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 162.159.200.1 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 195.238.84.23 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 78.83.137.244 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 188.126.9.95 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 95.43.236.49 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 162.159.200.123 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 84.54.128.102 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 185.117.82.71 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 78.159.131.45 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio ntpd[1013]: PROTO: 77.236.182.128 unlink local addr 192.168.62.25 -> Mar 27 19:51:09 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:09.655+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 27 19:51:13 volumio dhcpcd[836]: eth0: leased 192.168.62.25 for 120 seconds Mar 27 19:51:13 volumio dhcpcd[836]: eth0: adding route to 192.168.62.0/24 Mar 27 19:51:13 volumio dhcpcd[836]: eth0: adding default route via 192.168.62.4 Mar 27 19:51:13 volumio avahi-daemon[784]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.62.25. Mar 27 19:51:13 volumio avahi-daemon[784]: New relevant interface eth0.IPv4 for mDNS. Mar 27 19:51:13 volumio avahi-daemon[784]: Registering new address record for 192.168.62.25 on eth0.IPv4. Mar 27 19:51:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:51:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 27 19:51:13 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 27 19:51:13 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 27 19:51:13 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 27 19:51:13 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 27 19:51:13 volumio welcome[2678]: Resolved ip:[1] 192.168.62.25 Mar 27 19:51:13 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 27 19:51:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:51:13 volumio dhcpcd[736]: eth0: leased 192.168.62.25 for 120 seconds Mar 27 19:51:13 volumio dhcpcd[736]: eth0: adding route to 192.168.62.0/24 Mar 27 19:51:13 volumio dhcpcd[736]: eth0: adding default route via 192.168.62.4 Mar 27 19:51:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:51:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 27 19:51:13 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 27 19:51:13 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 27 19:51:13 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 27 19:51:13 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 27 19:51:13 volumio welcome[2710]: Resolved ip:[1] 192.168.62.25 Mar 27 19:51:13 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 27 19:51:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:51:14 volumio volumio[1128]: info: Received Get System Info Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:51:14 volumio volumio[1128]: info: Discovery: Getting this device information Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:14 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:14 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:14 volumio volumio[1128]: info: Discovery: adding 4b395812-8711-4d9f-95ab-5010e4102a2a Mar 27 19:51:14 volumio volumio[1128]: info: Discovery: Found device Volumio Mar 27 19:51:14 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:14 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:15 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:15.257+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 27 19:51:15 volumio ntpd[1013]: IO: Listen normally on 5 eth0 192.168.62.25:123 Mar 27 19:51:15 volumio ntpd[1013]: IO: new interface(s) found: waking up resolver Mar 27 19:51:19 volumio volumio[1128]: info: Get:2 http://archive.raspberrypi.com/debian bookworm/main armhf chromium armhf 1:146.0.7680.164-1~deb12u1+rpt1 [98.2 MB] Mar 27 19:51:19 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:19.984+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:24 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:24 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:24 volumio volumio[1128]: info: Listing playlists Mar 27 19:51:24 volumio volumio[1128]: info: Listing playlists Mar 27 19:51:34 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:34 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:41 volumio volumio[1128]: info: Get:3 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-common armhf 1:146.0.7680.164-1~deb12u1+rpt1 [34.7 MB] Mar 27 19:51:41 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:41.105+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:44 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:44 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:44 volumio volumio[1128]: info: Listing playlists Mar 27 19:51:44 volumio volumio[1128]: info: Listing playlists Mar 27 19:51:48 volumio volumio[1128]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-sandbox armhf 1:146.0.7680.164-1~deb12u1+rpt1 [121 kB] Mar 27 19:51:48 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:48.810+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:48 volumio volumio[1128]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-browser all 1:146.0.7680.164-1~deb12u1+rpt1 [114 kB] Mar 27 19:51:48 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:48.930+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:53 volumio volumio[1128]: info: Fetched 153 MB in 44s (3459 kB/s) Mar 27 19:51:53 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:53.875+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:54 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:51:54 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:51:55 volumio volumio[1128]: [620B blob data] Mar 27 19:51:55 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:55.172+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:55 volumio volumio[1128]: info: Preparing to unpack .../chromium-l10n_1%3a146.0.7680.164-1~deb12u1+rpt1_all.deb ... Mar 27 19:51:55 volumio volumio[1128]: info: Unpacking chromium-l10n (1:146.0.7680.164-1~deb12u1+rpt1) over (1:146.0.7680.153-1~deb12u1+rpt1) ... Mar 27 19:51:55 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:55.293+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:55 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:55.342+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:51:59 volumio volumio[1128]: info: Preparing to unpack .../chromium_1%3a146.0.7680.164-1~deb12u1+rpt1_armhf.deb ... Mar 27 19:51:59 volumio volumio5-onboarding[1807]: time=2026-03-27T19:51:59.886+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:00 volumio volumio[1128]: info: Unpacking chromium (1:146.0.7680.164-1~deb12u1+rpt1) over (1:146.0.7680.153-1~deb12u1+rpt1) ... Mar 27 19:52:00 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:00.016+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:04 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:04 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:04 volumio volumio[1128]: info: Listing playlists Mar 27 19:52:04 volumio volumio[1128]: info: Listing playlists Mar 27 19:52:06 volumio volumio[1128]: info: Preparing to unpack .../chromium-common_1%3a146.0.7680.164-1~deb12u1+rpt1_armhf.deb ... Mar 27 19:52:06 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:06.040+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:06 volumio volumio[1128]: info: Unpacking chromium-common (1:146.0.7680.164-1~deb12u1+rpt1) over (1:146.0.7680.153-1~deb12u1+rpt1) ... Mar 27 19:52:06 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:06.070+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:07 volumio volumio[1128]: info: Preparing to unpack .../chromium-sandbox_1%3a146.0.7680.164-1~deb12u1+rpt1_armhf.deb ... Mar 27 19:52:07 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:07.895+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:07 volumio volumio[1128]: info: Unpacking chromium-sandbox (1:146.0.7680.164-1~deb12u1+rpt1) over (1:146.0.7680.153-1~deb12u1+rpt1) ... Mar 27 19:52:07 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:07.914+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Preparing to unpack .../chromium-browser_1%3a146.0.7680.164-1~deb12u1+rpt1_all.deb ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.016+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Unpacking chromium-browser (1:146.0.7680.164-1~deb12u1+rpt1) over (1:146.0.7680.153-1~deb12u1+rpt1) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.047+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Setting up chromium-sandbox (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.213+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Setting up chromium-common (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.237+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Setting up chromium (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.257+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Setting up chromium-l10n (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.411+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Setting up chromium-browser (1:146.0.7680.164-1~deb12u1+rpt1) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.433+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.455+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Processing triggers for man-db (2.11.2-2) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.625+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:08 volumio volumio[1128]: info: Processing triggers for hicolor-icon-theme (0.17-2) ... Mar 27 19:52:08 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:08.882+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:10 volumio volumio[1128]: info: Installing fonts Mar 27 19:52:10 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:10.077+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:10 volumio volumio[1128]: info: Reading package lists... Mar 27 19:52:10 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:10.180+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:10 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:10 volumio volumio[1128]: info: CorePlayQueue::getTrack 1 Mar 27 19:52:10 volumio volumio[1128]: info: Building dependency tree... Mar 27 19:52:10 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:10.656+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:10 volumio volumio[1128]: info: Reading state information... Mar 27 19:52:10 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:10.669+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio[1128]: info: fonts-ipafont is already the newest version (00303-23). Mar 27 19:52:11 volumio volumio[1128]: info: fonts-vlgothic is already the newest version (20220612-1). Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.583+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio[1128]: info: fonts-thai-tlwg-ttf is already the newest version (1:0.7.3-1). Mar 27 19:52:11 volumio volumio[1128]: info: Suggested packages: Mar 27 19:52:11 volumio volumio[1128]: info: fonts-unfonts-extra Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.606+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.608+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.608+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.608+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio[1128]: info: The following NEW packages will be installed: Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.647+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:11 volumio volumio[1128]: info: fonts-arphic-gbsn00lp fonts-arphic-ukai fonts-unfonts-core Mar 27 19:52:11 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:11.666+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:12 volumio volumio[1128]: info: 0 upgraded, 3 newly installed, 0 to remove and 2 not upgraded. Mar 27 19:52:12 volumio volumio[1128]: info: Need to get 0 B/24.9 MB of archives. Mar 27 19:52:12 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:12.630+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:12 volumio volumio[1128]: info: After this operation, 57.1 MB of additional disk space will be used. Mar 27 19:52:12 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:12.644+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:12 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:12.645+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:12 volumio volumio[1128]: info: Selecting previously unselected package fonts-unfonts-core. Mar 27 19:52:12 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:12.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:13 volumio volumio[1128]: [620B blob data] Mar 27 19:52:13 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:13.669+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:13 volumio volumio[1128]: info: Preparing to unpack .../fonts-unfonts-core_1%3a1.0.2-080608-18_all.deb ... Mar 27 19:52:13 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:13.685+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:13 volumio volumio[1128]: info: Unpacking fonts-unfonts-core (1:1.0.2-080608-18) ... Mar 27 19:52:13 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:13.725+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:13 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:52:13 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Mar 27 19:52:13 volumio systemd[1]: welcome.service: Deactivated successfully. Mar 27 19:52:13 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Mar 27 19:52:13 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Mar 27 19:52:13 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Mar 27 19:52:13 volumio welcome[3041]: Resolved ip:[1] 192.168.62.25 Mar 27 19:52:13 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Mar 27 19:52:13 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Mar 27 19:52:14 volumio nmbd[1090]: [2026/03/27 19:52:14.148820, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 27 19:52:14 volumio nmbd[1090]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.62.25 for name WORKGROUP<1d>. Mar 27 19:52:14 volumio nmbd[1090]: This response was from IP 192.168.62.20, reporting an IP address of 192.168.62.20. Mar 27 19:52:14 volumio volumio[1128]: info: Received Get System Info Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:52:14 volumio volumio[1128]: info: Discovery: Getting this device information Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:14 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 27 19:52:14 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:14 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:15 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:15.254+02:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 27 19:52:16 volumio volumio[1128]: info: Mar 27 19:52:16 volumio volumio[1128]: ---------------------------- MPD announces state update: player Mar 27 19:52:16 volumio volumio[1128]: info: ControllerMpd::getState Mar 27 19:52:16 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Mar 27 19:52:16 volumio volumio[1128]: info: Mar 27 19:52:16 volumio volumio[1128]: ---------------------------- MPD announces state update: player Mar 27 19:52:16 volumio volumio[1128]: info: ControllerMpd::getState Mar 27 19:52:16 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Mar 27 19:52:16 volumio volumio[1128]: info: Mar 27 19:52:16 volumio volumio[1128]: ---------------------------- MPD announces state update: player Mar 27 19:52:16 volumio volumio[1128]: info: ControllerMpd::getState Mar 27 19:52:16 volumio volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Mar 27 19:52:16 volumio volumio[1128]: info: sendMpdCommand status took 2 milliseconds Mar 27 19:52:16 volumio volumio[1128]: info: sendMpdCommand status took 2 milliseconds Mar 27 19:52:16 volumio volumio[1128]: verbose: ControllerMpd::parseState Mar 27 19:52:16 volumio volumio[1128]: verbose: ControllerMpd::parseState Mar 27 19:52:16 volumio volumio[1128]: info: ControllerMpd::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 27 19:52:16 volumio volumio[1128]: verbose: CURRENT POSITION 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus play Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::stPlaybackTimer Mar 27 19:52:16 volumio volumio[1128]: info: ControllerMpd::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 27 19:52:16 volumio volumio[1128]: verbose: CURRENT POSITION 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: No code Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: ------------------------------ 50ms Mar 27 19:52:16 volumio volumio[1128]: info: ------------------------------ 50ms Mar 27 19:52:16 volumio volumio[1128]: info: sendMpdCommand status took 49 milliseconds Mar 27 19:52:16 volumio volumio[1128]: verbose: ControllerMpd::parseState Mar 27 19:52:16 volumio volumio[1128]: info: ControllerMpd::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::servicePushState Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 27 19:52:16 volumio volumio[1128]: verbose: CURRENT POSITION 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::syncState stateService stop Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: No code Mar 27 19:52:16 volumio volumio[1128]: info: CoreStateMachine::pushState Mar 27 19:52:16 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:16 volumio volumio[1128]: info: CoreCommandRouter::volumioPushState Mar 27 19:52:16 volumio volumio[1128]: info: ------------------------------ 75ms Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 27 19:52:16 volumio volumio[1128]: info: Selecting previously unselected package fonts-arphic-gbsn00lp. Mar 27 19:52:16 volumio volumio[1128]: info: Preparing to unpack .../fonts-arphic-gbsn00lp_2.11-16_all.deb ... Mar 27 19:52:16 volumio volumio[1128]: info: Unpacking fonts-arphic-gbsn00lp (2.11-16) ... Mar 27 19:52:16 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:16.170+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:16 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:16.188+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:16 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:16.188+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:16 volumio volumio[1128]: info: Selecting previously unselected package fonts-arphic-ukai. Mar 27 19:52:16 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:16.570+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:16 volumio volumio[1128]: info: Preparing to unpack .../fonts-arphic-ukai_0.2.20080216.2-5_all.deb ... Mar 27 19:52:16 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:16.582+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:16 volumio volumio[1128]: info: Unpacking fonts-arphic-ukai (0.2.20080216.2-5) ... Mar 27 19:52:16 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:16.603+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:18 volumio volumio[1128]: info: Setting up fonts-unfonts-core (1:1.0.2-080608-18) ... Mar 27 19:52:18 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:18.259+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:18 volumio volumio[1128]: info: Setting up fonts-arphic-gbsn00lp (2.11-16) ... Mar 27 19:52:18 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:18.304+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:18 volumio volumio[1128]: info: Setting up fonts-arphic-ukai (0.2.20080216.2-5) ... Mar 27 19:52:18 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:18.322+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:18 volumio volumio[1128]: info: Processing triggers for fontconfig (2.14.1-4) ... Mar 27 19:52:18 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:18.428+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:19 volumio volumio[1128]: info: Creating Kiosk data dir Mar 27 19:52:19 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:19.369+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:19 volumio volumio[1128]: info: Creating Volumio kiosk start script Mar 27 19:52:19 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:19.383+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:19 volumio volumio[1128]: info: Creating Systemd Unit for Kiosk Mar 27 19:52:19 volumio systemd[1]: Reloading. Mar 27 19:52:19 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:19.406+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:20 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:20 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:20 volumio volumio[1128]: info: Installing Virtual Keyboard Mar 27 19:52:20 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:20.429+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:21 volumio volumio[1128]: info: Allowing volumio to start an xsession Mar 27 19:52:21 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:21.776+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:21 volumio volumio[1128]: info: Configuring GPU/DRI permissions for volumio user Mar 27 19:52:21 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:21.791+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:21 volumio usermod[3152]: add 'volumio' to group 'render' Mar 27 19:52:21 volumio usermod[3152]: add 'volumio' to shadow group 'render' Mar 27 19:52:21 volumio volumio[1128]: info: Creating udev rules for persistent DRI device permissions Mar 27 19:52:21 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:21.870+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:21 volumio volumio[1128]: info: Reloading udev rules Mar 27 19:52:21 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:21.885+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:22 volumio volumio[1128]: info: Creating Mesa shader cache directory Mar 27 19:52:22 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:22.124+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:22 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:22 volumio volumio[1128]: info: Install script completed Mar 27 19:52:22 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:22.158+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:22 volumio volumio[1128]: info: Adding reference to registry Mar 27 19:52:22 volumio volumio5-onboarding[1807]: time=2026-03-27T19:52:22.175+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:52:22 volumio volumio[1128]: info: Done installing plugin. Mar 27 19:52:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:52:22 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 19:52:22 volumio volumio[1128]: Plugin install end detected on script Mar 27 19:52:22 volumio volumio[1128]: info: Folder /tmp/plugins removed Mar 27 19:52:22 volumio volumio[1128]: info: Folder /tmp/downloaded_plugin.zip removed Mar 27 19:52:22 volumio volumio[1128]: info: Folder /data/temp removed Mar 27 19:52:22 volumio systemd-logind[811]: Watching system buttons on /dev/input/event0 (soc:shutdown_button@11) Mar 27 19:52:22 volumio systemd-logind[811]: Watching system buttons on /dev/input/event3 (vc4-hdmi-1) Mar 27 19:52:22 volumio systemd-logind[811]: Watching system buttons on /dev/input/event1 (vc4-hdmi-0) Mar 27 19:52:24 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:24 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:24 volumio volumio[1128]: info: Listing playlists Mar 27 19:52:24 volumio volumio[1128]: info: Listing playlists Mar 27 19:52:24 volumio volumio[1128]: info: Enabling plugin touch_display Mar 27 19:52:24 volumio volumio[1128]: info: Loading plugin "touch_display"... Mar 27 19:52:25 volumio volumio[1128]: info: PLUGIN START: touch_display Mar 27 19:52:25 volumio volumio[1128]: info: Loading i18n strings for locale en Mar 27 19:52:25 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 19:52:25 volumio volumio[1128]: info: touch_display: No backlight interface detected. Mar 27 19:52:25 volumio volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 27 19:52:25 volumio sudo[3242]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 27 19:52:25 volumio sudo[3246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 27 19:52:25 volumio sudo[3242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:25 volumio sudo[3246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:25 volumio sudo[3244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 27 19:52:25 volumio sudo[3248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 27 19:52:25 volumio volumio[1128]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 27 19:52:25 volumio sudo[3248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:25 volumio sudo[3244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:25 volumio sudo[3242]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:25 volumio sudo[3254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 27 19:52:25 volumio sudo[3254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:25 volumio sudo[3254]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:25 volumio volumio[1128]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 27 19:52:25 volumio sudo[3257]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Mar 27 19:52:25 volumio sudo[3257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:25 volumio systemd[1]: Reloading. Mar 27 19:52:25 volumio sudo[3257]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:25 volumio volumio[1128]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 27 19:52:26 volumio volumio[1128]: info: Enabling plugin touch_display Mar 27 19:52:26 volumio volumio[1128]: info: Loading plugin "touch_display"... Mar 27 19:52:26 volumio volumio[1128]: info: Applying required configuration parameters for plugin touch_display Mar 27 19:52:26 volumio volumio[1128]: info: PLUGIN START: touch_display Mar 27 19:52:26 volumio volumio[1128]: info: Loading i18n strings for locale en Mar 27 19:52:26 volumio volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 19:52:26 volumio sudo[3280]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 27 19:52:26 volumio sudo[3280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio sudo[3280]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:26 volumio sudo[3282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 27 19:52:26 volumio sudo[3282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio volumio[1128]: info: touch_display: No backlight interface detected. Mar 27 19:52:26 volumio sudo[3287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 27 19:52:26 volumio sudo[3287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio sudo[3286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 27 19:52:26 volumio sudo[3286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio volumio[1128]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 27 19:52:26 volumio sudo[3293]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Mar 27 19:52:26 volumio sudo[3293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio sudo[3295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 27 19:52:26 volumio sudo[3295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio sudo[3293]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:26 volumio sudo[3295]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:26 volumio volumio[1128]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 27 19:52:26 volumio volumio[1128]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 27 19:52:26 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:26 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:26 volumio sudo[3248]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:26 volumio volumio[1128]: info: touch_display: systemctl daemon-reload succeeded. Mar 27 19:52:26 volumio sudo[3307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 27 19:52:26 volumio sudo[3307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:26 volumio systemd[1]: Reloading. Mar 27 19:52:28 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:28 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:28 volumio sudo[3246]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:28 volumio volumio[1128]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 27 19:52:28 volumio systemd[1]: Reloading. Mar 27 19:52:29 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:29 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:29 volumio systemd[1]: Reloading. Mar 27 19:52:29 volumio sudo[3286]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:29 volumio volumio[1128]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 27 19:52:30 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:30 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 27 19:52:30 volumio sudo[3287]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: systemctl daemon-reload succeeded. Mar 27 19:52:30 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Mar 27 19:52:30 volumio sudo[3367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 27 19:52:30 volumio sudo[3367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:52:30 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 27 19:52:30 volumio sudo[3307]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:30 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Mar 27 19:52:30 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: Volumio Kiosk started. Mar 27 19:52:30 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:30 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:30 volumio volumio[1128]: info: Done. Mar 27 19:52:30 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:30 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:30 volumio startx[3387]: xauth: file /home/volumio/.Xauthority does not exist Mar 27 19:52:30 volumio sudo[3244]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 27 19:52:30 volumio sudo[3282]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 27 19:52:30 volumio sudo[3367]: pam_unix(sudo:session): session closed for user root Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: Volumio Kiosk started. Mar 27 19:52:30 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:30 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:30 volumio volumio[1128]: info: Done. Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: X display number found: 0 Mar 27 19:52:30 volumio volumio[1128]: info: CoreCommandRouter::volumioGetState Mar 27 19:52:30 volumio volumio[1128]: info: CorePlayQueue::getTrack 0 Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: X display number found: 0 Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: X display number found: 0 Mar 27 19:52:30 volumio startx[3393]: X.Org X Server 1.21.1.7 Mar 27 19:52:30 volumio startx[3393]: X Protocol Version 11, Revision 0 Mar 27 19:52:30 volumio startx[3393]: Current Operating System: Linux volumio 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Mar 27 19:52:30 volumio startx[3393]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:800x480M@59D numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=E4:5F:01:0E:93:3D vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=14aa58b3-7c1f-46d2-b4f1-3f56bb2e4191 imgfile=/volumio_current.sqsh bootpart=UUID=5149-F647 datapart=UUID=0ee32d5b-7bbc-42b2-916c-34f329fe6377 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 27 19:52:30 volumio startx[3393]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 27 19:52:30 volumio startx[3393]: Current version of pixman: 0.44.0 Mar 27 19:52:30 volumio startx[3393]: Before reporting problems, check http://wiki.x.org Mar 27 19:52:30 volumio startx[3393]: to make sure that you have the latest version. Mar 27 19:52:30 volumio startx[3393]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 27 19:52:30 volumio startx[3393]: (++) from command line, (!!) notice, (II) informational, Mar 27 19:52:30 volumio startx[3393]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 27 19:52:30 volumio startx[3393]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Mar 27 19:52:30 2026 Mar 27 19:52:30 volumio startx[3393]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 27 19:52:30 volumio startx[3393]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 27 19:52:30 volumio volumio[1128]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 27 19:52:30 volumio volumio[1128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 19:52:30 volumio volumio[1128]: Error: connect EISCONN /tmp/.X11-unix/X0 Mar 27 19:52:30 volumio volumio[1128]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 27 19:52:30 volumio volumio[1128]: errno: -106, Mar 27 19:52:30 volumio volumio[1128]: code: 'EISCONN', Mar 27 19:52:30 volumio volumio[1128]: syscall: 'connect', Mar 27 19:52:30 volumio volumio[1128]: address: '/tmp/.X11-unix/X0' Mar 27 19:52:30 volumio volumio[1128]: } Mar 27 19:52:30 volumio volumio[1128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 19:52:31 volumio sudo[3421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 19:51' Mar 27 19:52:31 volumio sudo[3421]: 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"