Apr 13 20:54:01 volumio ntpd[951]: PROTO: 216.232.132.95 unlink local addr 192.168.50.167 -> Apr 13 20:54:14 volumio sudo[6237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 20:54:14 volumio sudo[6239]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 20:54:14 volumio sudo[6239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:14 volumio sudo[6237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:14 volumio sudo[6239]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:14 volumio sudo[6237]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:14 volumio volumio[1195]: verbose: New Socket.io Connection to 192.168.50.167 from 192.168.50.216 UA: Mozilla/5.0 (Android 16; Mobile; rv:149.0) Gecko/149.0 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 5 Apr 13 20:54:14 volumio sudo[6259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 13 20:54:14 volumio sudo[6259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:14 volumio sudo[6259]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:14 volumio sudo[6261]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 13 20:54:14 volumio sudo[6261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:14 volumio sudo[6261]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:14 volumio volumio[1195]: verbose: New Socket.io Connection to 192.168.50.167 from 192.168.50.216 UA: Mozilla/5.0 (Android 16; Mobile; rv:149.0) Gecko/149.0 Firefox/149.0 Engine version: 3 Transport: polling Total Clients: 5 Apr 13 20:54:14 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::volumioGetVisibleSources Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Apr 13 20:54:15 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 13 20:54:15 volumio volumio[1195]: info: Received Get System Info Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 20:54:15 volumio volumio[1195]: info: Discovery: Getting this device information Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Apr 13 20:54:15 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Apr 13 20:54:15 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Apr 13 20:54:15 volumio volumio[1195]: info: Listing playlists Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 20:54:15 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 13 20:54:15 volumio volumio[1195]: info: Selecting previously unselected package rpi-chromium-mods. Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.142Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Preparing to unpack .../11-rpi-chromium-mods_20250506_armhf.deb ... Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.192Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Unpacking rpi-chromium-mods (20250506) ... Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.374Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Selecting previously unselected package chromium-browser. Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.571Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Preparing to unpack .../12-chromium-browser_1%3a147.0.7727.55-1~deb12u1+rpt1_all.deb ... Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.601Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Unpacking chromium-browser (1:147.0.7727.55-1~deb12u1+rpt1) ... Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.632Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Selecting previously unselected package chromium-l10n. Apr 13 20:54:15 volumio volumio[1195]: info: Preparing to unpack .../13-chromium-l10n_1%3a147.0.7727.55-1~deb12u1+rpt1_all.deb ... Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.841Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.856Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:15 volumio volumio[1195]: info: Unpacking chromium-l10n (1:147.0.7727.55-1~deb12u1+rpt1) ... Apr 13 20:54:15 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:15.908Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 20:54:16 volumio volumio[1195]: info: Received Get System Info Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 20:54:16 volumio volumio[1195]: info: Discovery: Getting this device information Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Apr 13 20:54:16 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 20:54:16 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 20:54:17 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 20:54:17 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 20:54:19 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 20:54:19 volumio volumio[1195]: info: Received Get System Info Apr 13 20:54:19 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 20:54:19 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 20:54:19 volumio volumio[1195]: info: Discovery: Getting this device information Apr 13 20:54:19 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Apr 13 20:54:19 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Apr 13 20:54:19 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 20:54:24 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 13 20:54:26 volumio volumio[1195]: info: Selecting previously unselected package chromium-sandbox. Apr 13 20:54:26 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:26.768Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:26 volumio volumio[1195]: info: Preparing to unpack .../14-chromium-sandbox_1%3a147.0.7727.55-1~deb12u1+rpt1_armhf.deb ... Apr 13 20:54:26 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:26.807Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:26 volumio volumio[1195]: info: Unpacking chromium-sandbox (1:147.0.7727.55-1~deb12u1+rpt1) ... Apr 13 20:54:26 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:26.836Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:27 volumio volumio[1195]: info: Selecting previously unselected package fonts-liberation. Apr 13 20:54:27 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:27.056Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:27 volumio volumio[1195]: info: Preparing to unpack .../15-fonts-liberation_1%3a1.07.4-11_all.deb ... Apr 13 20:54:27 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:27.079Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:27 volumio volumio[1195]: info: Unpacking fonts-liberation (1:1.07.4-11) ... Apr 13 20:54:27 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:27.108Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:27 volumio volumio[1195]: info: Selecting previously unselected package libipc-system-simple-perl. Apr 13 20:54:28 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:28.014Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:28 volumio volumio[1195]: info: Preparing to unpack .../16-libipc-system-simple-perl_1.30-2_all.deb ... Apr 13 20:54:28 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:28.038Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:28 volumio volumio[1195]: info: Unpacking libipc-system-simple-perl (1.30-2) ... Apr 13 20:54:28 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:28.072Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:28 volumio volumio[1195]: info: Selecting previously unselected package libfile-basedir-perl. Apr 13 20:54:28 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:28.868Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:28 volumio volumio[1195]: info: Preparing to unpack .../17-libfile-basedir-perl_0.09-2_all.deb ... Apr 13 20:54:28 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:28.895Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:28 volumio volumio[1195]: info: Unpacking libfile-basedir-perl (0.09-2) ... Apr 13 20:54:28 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:28.940Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Selecting previously unselected package libfile-desktopentry-perl. Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.152Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Preparing to unpack .../18-libfile-desktopentry-perl_0.22-3_all.deb ... Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.178Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Unpacking libfile-desktopentry-perl (0.22-3) ... Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.212Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Selecting previously unselected package libfile-mimeinfo-perl. Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.380Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Preparing to unpack .../19-libfile-mimeinfo-perl_0.33-1_all.deb ... Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.407Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Unpacking libfile-mimeinfo-perl (0.33-1) ... Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.442Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Selecting previously unselected package libio-stringy-perl. Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.721Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Preparing to unpack .../20-libio-stringy-perl_2.111-3_all.deb ... Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.750Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:29 volumio volumio[1195]: info: Unpacking libio-stringy-perl (2.111-3) ... Apr 13 20:54:29 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:29.781Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:30 volumio volumio[1195]: info: Selecting previously unselected package libxml-parser-perl. Apr 13 20:54:30 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:30.077Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:30 volumio volumio[1195]: info: Preparing to unpack .../21-libxml-parser-perl_2.46-4+deb12u1_armhf.deb ... Apr 13 20:54:30 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:30.108Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:30 volumio volumio[1195]: info: Unpacking libxml-parser-perl (2.46-4+deb12u1) ... Apr 13 20:54:30 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:30.130Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:31 volumio volumio[1195]: info: Selecting previously unselected package libxml-twig-perl. Apr 13 20:54:31 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:31.063Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:31 volumio volumio[1195]: info: Preparing to unpack .../22-libxml-twig-perl_1%3a3.52-2_all.deb ... Apr 13 20:54:31 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:31.082Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:31 volumio volumio[1195]: info: Unpacking libxml-twig-perl (1:3.52-2) ... Apr 13 20:54:31 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:31.115Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:31 volumio volumio[1195]: info: Selecting previously unselected package libnet-dbus-perl. Apr 13 20:54:31 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:31.374Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:31 volumio volumio[1195]: info: Preparing to unpack .../23-libnet-dbus-perl_1.2.0-2_armhf.deb ... Apr 13 20:54:31 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:31.392Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:31 volumio volumio[1195]: info: Unpacking libnet-dbus-perl (1.2.0-2) ... Apr 13 20:54:31 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:31.430Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Selecting previously unselected package libtie-ixhash-perl. Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.152Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Preparing to unpack .../24-libtie-ixhash-perl_1.23-4_all.deb ... Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.176Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Unpacking libtie-ixhash-perl (1.23-4) ... Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.203Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Selecting previously unselected package libupower-glib3:armhf. Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.383Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Preparing to unpack .../25-libupower-glib3_0.99.20-2_armhf.deb ... Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.411Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Unpacking libupower-glib3:armhf (0.99.20-2) ... Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.437Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Selecting previously unselected package libwidevinecdm0. Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.651Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Preparing to unpack .../26-libwidevinecdm0_4.10.2662.3+1_armhf.deb ... Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.675Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:32 volumio volumio[1195]: info: Unpacking libwidevinecdm0 (4.10.2662.3+1) ... Apr 13 20:54:32 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:32.709Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 13 20:54:33 volumio volumio[1195]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 13 20:54:33 volumio volumio[1195]: info: Received Get System Version Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 20:54:33 volumio volumio[1195]: info: Received Get System Info Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 13 20:54:33 volumio volumio[1195]: info: Discovery: Getting this device information Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::volumioGetState Apr 13 20:54:33 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Apr 13 20:54:33 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 13 20:54:34 volumio volumio[1195]: info: Selecting previously unselected package libx11-protocol-perl. Apr 13 20:54:34 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:34.524Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:34 volumio volumio[1195]: info: Preparing to unpack .../27-libx11-protocol-perl_0.56-9_all.deb ... Apr 13 20:54:34 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:34.552Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:34 volumio volumio[1195]: info: Unpacking libx11-protocol-perl (0.56-9) ... Apr 13 20:54:34 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:34.600Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Selecting previously unselected package libxml-xpathengine-perl. Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.260Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Preparing to unpack .../28-libxml-xpathengine-perl_0.14-2_all.deb ... Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.285Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Unpacking libxml-xpathengine-perl (0.14-2) ... Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.310Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Selecting previously unselected package notification-daemon. Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.633Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Preparing to unpack .../29-notification-daemon_3.20.0-4_armhf.deb ... Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.661Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Unpacking notification-daemon (3.20.0-4) ... Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.707Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Selecting previously unselected package upower. Apr 13 20:54:35 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:35.979Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:35 volumio volumio[1195]: info: Preparing to unpack .../30-upower_0.99.20-2_armhf.deb ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.005Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Unpacking upower (0.99.20-2) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.040Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio dbus-daemon[753]: [system] Reloaded configuration Apr 13 20:54:36 volumio shairport-sync[2629]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio dbus-daemon[753]: [system] Reloaded configuration Apr 13 20:54:36 volumio shairport-sync[2629]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio5-onboarding[1968]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio5-onboarding[1968]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio dbus-daemon[753]: [system] Reloaded configuration Apr 13 20:54:36 volumio shairport-sync[2629]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio5-onboarding[1968]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio dbus-daemon[753]: [system] Reloaded configuration Apr 13 20:54:36 volumio volumio5-onboarding[1968]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio shairport-sync[2629]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:36 volumio volumio[1195]: info: Setting up libxml-parser-perl (2.46-4+deb12u1) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.649Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up libdouble-conversion3:armhf (3.2.1-1) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.697Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up libtie-ixhash-perl (1.23-4) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.742Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up chromium-codecs-ffmpeg-extra (126.0.6478.164-rpt1) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.779Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up libio-stringy-perl (2.111-3) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.824Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up libxml-twig-perl (1:3.52-2) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.888Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up libnet-dbus-perl (1.2.0-2) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.950Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:36 volumio volumio[1195]: info: Setting up chromium-sandbox (1:147.0.7727.55-1~deb12u1+rpt1) ... Apr 13 20:54:36 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:36.988Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up notification-daemon (3.20.0-4) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.033Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libxnvctrl0:armhf (525.85.05-3~deb12u1) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.113Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/pi_screen_setup/volumio/bookworm/armhf Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.145Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libnspr4:armhf (2:4.35-1) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.175Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libipc-system-simple-perl (1.30-2) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.264Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libxml-xpathengine-perl (0.14-2) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.341Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libopenh264-7:armhf (2.3.1+dfsg-3+deb12u2) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.394Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up fonts-liberation (1:1.07.4-11) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.451Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libupower-glib3:armhf (0.99.20-2) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.744Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up libharfbuzz-subset0:armhf (6.0.0+dfsg-3) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.818Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up zenoty (0.2) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.905Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:37 volumio volumio[1195]: info: Setting up xdg-utils (1.1.3-4.1) ... Apr 13 20:54:37 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:37.992Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:38 volumio volumio[1195]: info: update-alternatives: using /usr/bin/xdg-open to provide /usr/bin/open (open) in auto mode Apr 13 20:54:38 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:38.065Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:38 volumio volumio[1195]: info: Setting up libx11-protocol-perl (0.56-9) ... Apr 13 20:54:38 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:38.127Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:38 volumio volumio[1195]: info: Setting up libfile-basedir-perl (0.09-2) ... Apr 13 20:54:38 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:38.226Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:38 volumio volumio[1195]: info: Setting up upower (0.99.20-2) ... Apr 13 20:54:38 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:38.294Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:38 volumio systemd[1]: Reloading. Apr 13 20:54:38 volumio volumio[1195]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/pi_screen_setup/volumio/bookworm/armhf Apr 13 20:54:38 volumio volumio[1195]: info: Folder /tmp/plugins removed Apr 13 20:54:38 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:38.997Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:39 volumio volumio[1195]: info: Check plugin dependencies Apr 13 20:54:39 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:39.423Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:39 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 20:54:39 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:39.512Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:39 volumio volumio[1195]: info: Checking if plugin already exists Apr 13 20:54:39 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:39.517Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:39 volumio volumio[1195]: info: Rename folder Apr 13 20:54:39 volumio volumio[1195]: info: Folder /tmp/downloaded_plugin.zip removed Apr 13 20:54:39 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:39.555Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:39 volumio volumio[1195]: info: Move to category Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.604Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio[1195]: info: Checking if install.sh is present Apr 13 20:54:40 volumio volumio[1195]: info: Executing install.sh Apr 13 20:54:40 volumio sudo[6571]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/pi_screen_setup/install.sh Apr 13 20:54:40 volumio sudo[6571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:40 volumio volumio[1195]: info: Installing pi_screen_setup... Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.776Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio[1195]: info: Installing pi_screen_setup... Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.781Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio[1195]: info: Detected: Raspberry Pi 3 Model B Plus Rev 1.3 Apr 13 20:54:40 volumio volumio[1195]: info: Creating sudoers entry for pi_screen_setup... Apr 13 20:54:40 volumio volumio[1195]: info: Detected: Raspberry Pi 3 Model B Plus Rev 1.3 Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.864Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.866Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.869Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio[1195]: info: Creating sudoers entry for pi_screen_setup... Apr 13 20:54:40 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:40.879Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:40 volumio volumio[1195]: info: /etc/sudoers.d/volumio-user-pi_screen_setup: parsed OK Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.023Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Sudoers configuration complete. Apr 13 20:54:41 volumio volumio[1195]: info: Creating data directories... Apr 13 20:54:41 volumio sudo[6571]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.121Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.128Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: /etc/sudoers.d/volumio-user-pi_screen_setup: parsed OK Apr 13 20:54:41 volumio volumio[1195]: info: Sudoers configuration complete. Apr 13 20:54:41 volumio volumio[1195]: info: Creating data directories... Apr 13 20:54:41 volumio volumio[1195]: info: Boot partition verified. Apr 13 20:54:41 volumio volumio[1195]: info: Checking factory backups... Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of config.txt... Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.143Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.143Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.143Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.144Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.144Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.144Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Install script completed Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.175Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Adding reference to registry Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.182Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Done installing plugin. Apr 13 20:54:41 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 20:54:41 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 20:54:41 volumio volumio[1195]: info: Boot partition verified. Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.211Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Checking factory backups... Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of config.txt... Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.246Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.261Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Folder /tmp/plugins removed Apr 13 20:54:41 volumio volumio[1195]: info: Folder /tmp/downloaded_plugin.zip removed Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of cmdline.txt... Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of volumioconfig.txt... Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.316Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of userconfig.txt... Apr 13 20:54:41 volumio volumio[1195]: info: Factory backups created. Apr 13 20:54:41 volumio volumio[1195]: info: Checking for existing display configuration... Apr 13 20:54:41 volumio volumio[1195]: info: Found display settings in volumioconfig.txt Apr 13 20:54:41 volumio volumio[1195]: info: Apr 13 20:54:41 volumio volumio[1195]: info: NOTE: Existing display configuration detected. Apr 13 20:54:41 volumio volumio[1195]: info: The plugin will offer to migrate these settings on first use. Apr 13 20:54:41 volumio volumio[1195]: info: Apr 13 20:54:41 volumio volumio[1195]: info: Created placeholder /boot/videoconfig.txt Apr 13 20:54:41 volumio volumio[1195]: info: Installation complete. Apr 13 20:54:41 volumio volumio[1195]: Plugin install end detected on script Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.332Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.333Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.333Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.333Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.334Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.334Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.335Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.335Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.336Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.336Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.337Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Folder /data/temp removed Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of cmdline.txt... Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of volumioconfig.txt... Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.359Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Creating factory backup of userconfig.txt... Apr 13 20:54:41 volumio volumio[1195]: info: Factory backups created. Apr 13 20:54:41 volumio volumio[1195]: info: Checking for existing display configuration... Apr 13 20:54:41 volumio volumio[1195]: info: Found display settings in volumioconfig.txt Apr 13 20:54:41 volumio volumio[1195]: info: Apr 13 20:54:41 volumio volumio[1195]: info: NOTE: Existing display configuration detected. Apr 13 20:54:41 volumio volumio[1195]: info: The plugin will offer to migrate these settings on first use. Apr 13 20:54:41 volumio volumio[1195]: info: Apr 13 20:54:41 volumio volumio[1195]: info: Created placeholder /boot/videoconfig.txt Apr 13 20:54:41 volumio volumio[1195]: info: Installation complete. Apr 13 20:54:41 volumio volumio[1195]: Plugin install end detected on script Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.439Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.442Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.456Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.457Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.465Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.467Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.472Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.477Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.487Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.490Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio5-onboarding[1968]: time=2026-04-14T00:54:41.495Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 13 20:54:41 volumio volumio[1195]: info: Apr 13 20:54:41 volumio volumio[1195]: upower.service is a disabled or a static unit, not starting it. Apr 13 20:54:41 volumio volumio[1195]: info: Setting up libnss3:armhf (2:3.87.1-1+deb12u2) ... Apr 13 20:54:41 volumio volumio[1195]: info: Setting up libwidevinecdm0 (4.10.2662.3+1) ... Apr 13 20:54:41 volumio volumio[1195]: info: Apr 13 20:54:41 volumio volumio[1195]: upower.service is a disabled or a static unit, not starting it. Apr 13 20:54:41 volumio volumio[1195]: info: Setting up libnss3:armhf (2:3.87.1-1+deb12u2) ... Apr 13 20:54:41 volumio volumio[1195]: info: Setting up libwidevinecdm0 (4.10.2662.3+1) ... Apr 13 20:54:42 volumio dbus-daemon[753]: [system] Reloaded configuration Apr 13 20:54:42 volumio volumio5-onboarding[1968]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:42 volumio shairport-sync[2629]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:42 volumio volumio[1195]: info: Enabling plugin pi_screen_setup Apr 13 20:54:42 volumio volumio[1195]: info: Loading plugin "pi_screen_setup"... Apr 13 20:54:43 volumio volumio[1195]: info: PLUGIN START: pi_screen_setup Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Starting plugin Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Detected model: Raspberry Pi 3 Model B Plus Rev 1.3 Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Detected SoC: bcm2837 Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Detected RAM: 869 MB Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 3","model_raw":"Raspberry Pi 3 Model B Plus Rev 1.3","soc":"bcm2837","ram_mb":869,"hdmi_ports":1,"dsi_ports":["dsi0"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d","is_pi":true} Apr 13 20:54:43 volumio volumio[1195]: info: pi_screen_setup: Management server started on port 4567 Apr 13 20:54:43 volumio volumio[1195]: info: Done. Apr 13 20:54:43 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:43 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:43 volumio volumio[1195]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Apr 13 20:54:49 volumio volumio[1195]: info: Enabling plugin touch_display Apr 13 20:54:49 volumio volumio[1195]: info: Loading plugin "touch_display"... Apr 13 20:54:49 volumio volumio[1195]: info: PLUGIN START: touch_display Apr 13 20:54:49 volumio volumio[1195]: info: Loading i18n strings for locale en Apr 13 20:54:49 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 13 20:54:49 volumio sudo[6656]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Apr 13 20:54:49 volumio sudo[6656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio sudo[6656]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:49 volumio sudo[6659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 13 20:54:49 volumio sudo[6659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio volumio[1195]: info: touch_display: Backlight interface detected. Apr 13 20:54:49 volumio sudo[6661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 13 20:54:49 volumio sudo[6661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio volumio[1195]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 13 20:54:49 volumio sudo[6664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 13 20:54:49 volumio sudo[6664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio sudo[6668]: volumio : PWD=/ ; USER=root ; COMMAND=/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/ Apr 13 20:54:49 volumio sudo[6668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio sudo[6668]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:49 volumio volumio[1195]: info: touch_display: Raspberry Pi Foundation touch screen detected. Apr 13 20:54:49 volumio sudo[6673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Apr 13 20:54:49 volumio sudo[6673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio volumio[1195]: info: touch_display: IframeKeyboardBridge extension installed successfully Apr 13 20:54:49 volumio sudo[6673]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:49 volumio sudo[6675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 13 20:54:49 volumio sudo[6675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:49 volumio volumio[1195]: info: touch_display: File permissions for backlight brightness control set. Apr 13 20:54:49 volumio systemd[1]: Reloading. Apr 13 20:54:49 volumio sudo[6675]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:49 volumio volumio[1195]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 13 20:54:50 volumio volumio[1195]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 13 20:54:50 volumio volumio[1195]: info: touch_display: About to detect HDMI ports... Apr 13 20:54:50 volumio volumio[1195]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Apr 13 20:54:51 volumio sudo[6661]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:51 volumio systemd[1]: Reloading. Apr 13 20:54:51 volumio volumio[1195]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 13 20:54:52 volumio sudo[6664]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:52 volumio volumio[1195]: info: touch_display: systemctl daemon-reload succeeded. Apr 13 20:54:52 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Apr 13 20:54:52 volumio sudo[6717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 13 20:54:52 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Apr 13 20:54:52 volumio sudo[6717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:54:52 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Apr 13 20:54:52 volumio sudo[6659]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:52 volumio volumio[1195]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 13 20:54:52 volumio sudo[6717]: pam_unix(sudo:session): session closed for user root Apr 13 20:54:52 volumio volumio[1195]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Apr 13 20:54:52 volumio volumio[1195]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Apr 13 20:54:52 volumio volumio[1195]: info: Error: Error Apr 13 20:55:02 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 13 20:55:02 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 13 20:55:04 volumio sudo[6749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 13 20:55:04 volumio sudo[6749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:55:04 volumio sudo[6749]: pam_unix(sudo:session): session closed for user root Apr 13 20:55:04 volumio sudo[6751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Apr 13 20:55:04 volumio sudo[6751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:55:04 volumio volumio[1195]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 13 20:55:04 volumio volumio[1195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 20:55:04 volumio sudo[6754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Apr 13 20:55:04 volumio sudo[6754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:55:04 volumio sudo[6757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Apr 13 20:55:04 volumio sudo[6757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 13 20:55:04 volumio volumio[1195]: TypeError: Cannot read properties of undefined (reading 'split') Apr 13 20:55:04 volumio volumio[1195]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Apr 13 20:55:04 volumio volumio[1195]: at ChildProcess.exithandler (node:child_process:413:7) Apr 13 20:55:04 volumio volumio[1195]: at ChildProcess.emit (node:events:514:28) Apr 13 20:55:04 volumio volumio[1195]: at maybeClose (node:internal/child_process:1105:16) Apr 13 20:55:04 volumio volumio[1195]: at Socket. (node:internal/child_process:457:11) Apr 13 20:55:04 volumio volumio[1195]: at Socket.emit (node:events:514:28) Apr 13 20:55:04 volumio volumio[1195]: at Pipe. (node:net:337:12) Apr 13 20:55:04 volumio volumio[1195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 13 20:55:04 volumio sudo[6757]: pam_unix(sudo:session): session closed for user root Apr 13 20:55:04 volumio systemd[1]: Reloading. Apr 13 20:55:05 volumio sudo[6791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-13 20:54' Apr 13 20:55:05 volumio sudo[6791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"